builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0370 starttime: 1494872878.39 results: success (0) buildid: 20170515095425 builduid: d83dac17ae05489bb274c6bc7a8864db revision: 241a875b457fd4164cfe96f57865dd0969f20909 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:58.387262) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:58.387662) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:58.387983) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:58.415694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-15 11:27:58.415987) ========= 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.xASM1xf6B3/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.rcINlBPyID/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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-15 11:27:58-- 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% 19.2M=0.001s 2017-05-15 11:27:58 (19.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.318924 ========= master_lag: 3.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-15 11:28:01.748681) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:28:01.749011) ========= 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.xASM1xf6B3/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.rcINlBPyID/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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.037651 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:28:01.810910) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-15 11:28:01.811253) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 241a875b457fd4164cfe96f57865dd0969f20909 --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 241a875b457fd4164cfe96f57865dd0969f20909 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xASM1xf6B3/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.rcINlBPyID/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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-15 11:28:01,922 truncating revision to first 12 chars 2017-05-15 11:28:01,922 Setting DEBUG logging. 2017-05-15 11:28:01,922 attempt 1/10 2017-05-15 11:28:01,922 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/241a875b457f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 11:28:04,260 unpacking tar archive at: mozilla-beta-241a875b457f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.614328 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-15 11:28:04.449998) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 11:28:04.450443) ========= script_repo_revision: 241a875b457fd4164cfe96f57865dd0969f20909 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 11:28:04.450960) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 11:28:04.451416) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 11:28:04.470407) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 11:28:04.470781) ========= /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.xASM1xf6B3/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.rcINlBPyID/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:28:04 INFO - MultiFileLogger online at 20170515 11:28:04 in /builds/slave/test 11:28:04 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 11:28:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:28:04 INFO - {'append_to_log': False, 11:28:04 INFO - 'base_work_dir': '/builds/slave/test', 11:28:04 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:28:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:28:04 INFO - 'branch': 'Mozilla-Beta', 11:28:04 INFO - 'buildbot_json_path': 'buildprops.json', 11:28:04 INFO - 'config_files': ('talos/mac_config.py',), 11:28:04 INFO - 'default_actions': ('clobber', 11:28:04 INFO - 'read-buildbot-config', 11:28:04 INFO - 'download-and-extract', 11:28:04 INFO - 'populate-webroot', 11:28:04 INFO - 'create-virtualenv', 11:28:04 INFO - 'install', 11:28:04 INFO - 'run-tests'), 11:28:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:28:04 INFO - 'download_minidump_stackwalk': True, 11:28:04 INFO - 'download_symbols': 'ondemand', 11:28:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:28:04 INFO - 'tooltool.py': '/tools/tooltool.py', 11:28:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:28:04 INFO - '/tools/misc-python/virtualenv.py')}, 11:28:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:28:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:28:04 INFO - 'gecko_profile': False, 11:28:04 INFO - 'gecko_profile_interval': 0, 11:28:04 INFO - 'installer_path': 'installer.exe', 11:28:04 INFO - 'log_level': 'info', 11:28:04 INFO - 'log_name': 'talos', 11:28:04 INFO - 'log_to_console': True, 11:28:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:28:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:28:04 INFO - 'opt_config_files': (), 11:28:04 INFO - 'pip_index': False, 11:28:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:28:04 INFO - 'cmd': ('bash', 11:28:04 INFO - '-c', 11:28:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:28:04 INFO - 'enabled': True, 11:28:04 INFO - 'halt_on_failure': False, 11:28:04 INFO - 'name': 'check_screen_resolution'},), 11:28:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:28:04 INFO - 'cmd': ('bash', 11:28:04 INFO - '-c', 11:28:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:28:04 INFO - 'enabled': True, 11:28:04 INFO - 'halt_on_failure': False, 11:28:04 INFO - 'name': 'check_screen_resolution'},), 11:28:04 INFO - 'run_cmd_checks_enabled': True, 11:28:04 INFO - 'suite': 'dromaeojs', 11:28:04 INFO - 'system_bits': '32', 11:28:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:28:04 INFO - 'title': 't-yosemite-r7-0370', 11:28:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:28:04 INFO - 'use_talos_json': True, 11:28:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:28:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:28:04 INFO - 'work_dir': 'build'} 11:28:04 INFO - [mozharness: 2017-05-15 18:28:04.688796Z] Running clobber step. 11:28:04 INFO - Running pre-action listener: _resource_record_pre_action 11:28:04 INFO - Running main action method: clobber 11:28:04 INFO - rmtree: /builds/slave/test/build 11:28:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:28:07 INFO - Running post-action listener: _resource_record_post_action 11:28:07 INFO - [mozharness: 2017-05-15 18:28:07.097308Z] Finished clobber step (success) 11:28:07 INFO - [mozharness: 2017-05-15 18:28:07.097445Z] Running read-buildbot-config step. 11:28:07 INFO - Running pre-action listener: _resource_record_pre_action 11:28:07 INFO - Running main action method: read_buildbot_config 11:28:07 INFO - Using buildbot properties: 11:28:07 INFO - { 11:28:07 INFO - "project": "", 11:28:07 INFO - "product": "firefox", 11:28:07 INFO - "script_repo_revision": "production", 11:28:07 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 11:28:07 INFO - "repository": "", 11:28:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 11:28:07 INFO - "buildid": "20170515095425", 11:28:07 INFO - "pgo_build": "False", 11:28:07 INFO - "basedir": "/builds/slave/test", 11:28:07 INFO - "buildnumber": 1, 11:28:07 INFO - "slavename": "t-yosemite-r7-0370", 11:28:07 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:28:07 INFO - "platform": "yosemite_r7", 11:28:07 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:28:07 INFO - "branch": "mozilla-beta", 11:28:07 INFO - "repo_path": "releases/mozilla-beta", 11:28:07 INFO - "revision": "241a875b457fd4164cfe96f57865dd0969f20909", 11:28:07 INFO - "stage_platform": "macosx64", 11:28:07 INFO - "builduid": "d83dac17ae05489bb274c6bc7a8864db", 11:28:07 INFO - "slavebuilddir": "test" 11:28:07 INFO - } 11:28:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 11:28:07 INFO - Running post-action listener: _resource_record_post_action 11:28:07 INFO - [mozharness: 2017-05-15 18:28:07.100685Z] Finished read-buildbot-config step (success) 11:28:07 INFO - [mozharness: 2017-05-15 18:28:07.100817Z] Running download-and-extract step. 11:28:07 INFO - Running pre-action listener: _resource_record_pre_action 11:28:07 INFO - Running main action method: download_and_extract 11:28:07 INFO - mkdir: /builds/slave/test/build/tests 11:28:07 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')]} 11:28:07 INFO - trying https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 11:28:07 INFO - Downloading https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/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 11:28:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/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 11:28:08 INFO - Downloaded 1287 bytes. 11:28:08 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:28:08 INFO - Using the following test package requirements: 11:28:08 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:08 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 11:28:08 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:28:08 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:08 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 11:28:08 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:08 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 11:28:08 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:28:08 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:08 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 11:28:08 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:28:08 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:08 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 11:28:08 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:08 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 11:28:08 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:08 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 11:28:08 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:08 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 11:28:08 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 11:28:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:28:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:28:08 INFO - Fetch https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:28:09 INFO - Expected file size: 51227364 11:28:09 INFO - Obtained file size: 51227364 11:28:26 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 11:28:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:28:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:28:26 INFO - Fetch https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:28:28 INFO - Expected file size: 51227364 11:28:28 INFO - Obtained file size: 51227364 11:28:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 11:28:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 11:28:43 INFO - Fetch https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 11:28:44 INFO - Expected file size: 14404449 11:28:44 INFO - Obtained file size: 14404449 11:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:44 INFO - trying https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:28:44 INFO - Downloading https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 11:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/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 11:28:46 INFO - Downloaded 59540102 bytes. 11:28:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:28:46 INFO - mkdir: /builds/slave/test/properties 11:28:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:28:46 INFO - Writing to file /builds/slave/test/properties/build_url 11:28:46 INFO - Contents: 11:28:46 INFO - build_url:https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:28:47 INFO - Running post-action listener: _resource_record_post_action 11:28:47 INFO - Running post-action listener: set_extra_try_arguments 11:28:47 INFO - [mozharness: 2017-05-15 18:28:47.002063Z] Finished download-and-extract step (success) 11:28:47 INFO - [mozharness: 2017-05-15 18:28:47.002168Z] Running populate-webroot step. 11:28:47 INFO - Running pre-action listener: _resource_record_pre_action 11:28:47 INFO - Running main action method: populate_webroot 11:28:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:28:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:28:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:28:47 INFO - '--tppagecycles', 11:28:47 INFO - '7'], 11:28:47 INFO - 'tests': ['tsvgm']}}, 11:28:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:28:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:28:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:28:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:28:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:28:47 INFO - 'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:28:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:28:47 INFO - 'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['damp', 'tps']}, 11:28:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:28:47 INFO - 'tests': ['damp', 'tps']}, 11:28:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['dromaeo_dom']}, 11:28:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:28:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['basic_compositor_video']}, 11:28:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:28:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['a11yr', 11:28:47 INFO - 'ts_paint', 11:28:47 INFO - 'tpaint', 11:28:47 INFO - 'sessionrestore', 11:28:47 INFO - 'sessionrestore_no_auto_restore', 11:28:47 INFO - 'tabpaint']}, 11:28:47 INFO - 'other-e10s': {'tests': ['a11yr', 11:28:47 INFO - 'ts_paint', 11:28:47 INFO - 'tpaint', 11:28:47 INFO - 'sessionrestore', 11:28:47 INFO - 'sessionrestore_no_auto_restore', 11:28:47 INFO - 'tabpaint']}, 11:28:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:28:47 INFO - 'ts_paint', 11:28:47 INFO - 'tpaint', 11:28:47 INFO - 'sessionrestore', 11:28:47 INFO - 'sessionrestore_no_auto_restore', 11:28:47 INFO - 'tabpaint']}, 11:28:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:28:47 INFO - 'ts_paint', 11:28:47 INFO - 'tpaint', 11:28:47 INFO - 'sessionrestore', 11:28:47 INFO - 'sessionrestore_no_auto_restore', 11:28:47 INFO - 'tabpaint']}, 11:28:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['a11yr', 11:28:47 INFO - 'ts_paint', 11:28:47 INFO - 'tpaint', 11:28:47 INFO - 'sessionrestore', 11:28:47 INFO - 'sessionrestore_no_auto_restore', 11:28:47 INFO - 'tabpaint']}, 11:28:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['a11yr', 11:28:47 INFO - 'ts_paint', 11:28:47 INFO - 'tpaint', 11:28:47 INFO - 'sessionrestore', 11:28:47 INFO - 'sessionrestore_no_auto_restore', 11:28:47 INFO - 'tabpaint']}, 11:28:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['tsvgx', 11:28:47 INFO - 'tsvgr_opacity', 11:28:47 INFO - 'tart', 11:28:47 INFO - 'tscrollx', 11:28:47 INFO - 'cart', 11:28:47 INFO - 'tsvg_static']}, 11:28:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:28:47 INFO - 'tsvgr_opacity', 11:28:47 INFO - 'tart', 11:28:47 INFO - 'tscrollx', 11:28:47 INFO - 'cart', 11:28:47 INFO - 'tsvg_static']}, 11:28:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:28:47 INFO - 'talos_options': ['--disable-e10s'], 11:28:47 INFO - 'tests': ['tp5o']}, 11:28:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:28:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:28:47 INFO - 'talos_options': ['--disable-e10s', 11:28:47 INFO - '--xperf_path', 11:28:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:47 INFO - 'tests': ['tp5n']}, 11:28:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:28:47 INFO - 'talos_options': ['--xperf_path', 11:28:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:47 INFO - 'tests': ['tp5n']}}, 11:28:47 INFO - 'talos.zip': {'path': '', 11:28:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:28:47 INFO - Running post-action listener: _resource_record_post_action 11:28:47 INFO - [mozharness: 2017-05-15 18:28:47.020243Z] Finished populate-webroot step (success) 11:28:47 INFO - [mozharness: 2017-05-15 18:28:47.020375Z] Running create-virtualenv step. 11:28:47 INFO - Running pre-action listener: _resource_record_pre_action 11:28:47 INFO - Running main action method: create_virtualenv 11:28:47 INFO - Creating virtualenv /builds/slave/test/build/venv 11:28:47 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 11:28:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:28:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:28:47 INFO - Using real prefix '/tools/python27' 11:28:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:28:47 INFO - Installing distribute.............................................................................................................................................................................................done. 11:28:50 INFO - Installing pip.................done. 11:28:50 INFO - Return code: 0 11:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:28:51 INFO - Reading from file tmpfile_stdout 11:28:51 INFO - Output received: 11:28:51 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) 11:28:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:28:51 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')]} 11:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:51 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')]} 11:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:51 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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:28:51 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 11:28:51 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 11:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:51 INFO - 'HOME': '/Users/cltbld', 11:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:51 INFO - 'LOGNAME': 'cltbld', 11:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:51 INFO - 'MOZ_NO_REMOTE': '1', 11:28:51 INFO - 'NO_EM_RESTART': '1', 11:28:51 INFO - 'PAGER': '/bin/cat', 11:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:51 INFO - 'PWD': '/builds/slave/test', 11:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:51 INFO - 'SHELL': '/bin/bash', 11:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:28:51 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:51 INFO - 'USER': 'cltbld', 11:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:51 INFO - 'XPC_FLAGS': '0x0', 11:28:51 INFO - 'XPC_SERVICE_NAME': '0', 11:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:51 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 11:28:51 INFO - Cleaning up... 11:28:51 INFO - Return code: 0 11:28:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:28:51 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')]} 11:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:51 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')]} 11:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:51 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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:28:51 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 11:28:51 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 11:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:51 INFO - 'HOME': '/Users/cltbld', 11:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:51 INFO - 'LOGNAME': 'cltbld', 11:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:51 INFO - 'MOZ_NO_REMOTE': '1', 11:28:51 INFO - 'NO_EM_RESTART': '1', 11:28:51 INFO - 'PAGER': '/bin/cat', 11:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:51 INFO - 'PWD': '/builds/slave/test', 11:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:51 INFO - 'SHELL': '/bin/bash', 11:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:28:51 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:51 INFO - 'USER': 'cltbld', 11:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:51 INFO - 'XPC_FLAGS': '0x0', 11:28:51 INFO - 'XPC_SERVICE_NAME': '0', 11:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:51 INFO - Downloading/unpacking psutil>=3.1.1 11:28:51 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 11:28:51 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 11:28:51 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 11:28:51 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 11:28:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:28:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:53 INFO - Installing collected packages: psutil 11:28:53 INFO - Running setup.py install for psutil 11:28:53 INFO - building 'psutil._psutil_osx' extension 11:28:53 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 11:28:54 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 11:28:54 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 11:28:54 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 11:28:54 INFO - building 'psutil._psutil_posix' extension 11:28:54 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 11:28:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:28:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:28:54 INFO - ^ 11:28:54 INFO - 1 warning generated. 11:28:54 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 11:28:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:54 INFO - Successfully installed psutil 11:28:54 INFO - Cleaning up... 11:28:54 INFO - Return code: 0 11:28:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:28:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:55 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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:28:55 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 11:28:55 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 11:28:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:28:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:55 INFO - 'HOME': '/Users/cltbld', 11:28:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:55 INFO - 'LOGNAME': 'cltbld', 11:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:55 INFO - 'MOZ_NO_REMOTE': '1', 11:28:55 INFO - 'NO_EM_RESTART': '1', 11:28:55 INFO - 'PAGER': '/bin/cat', 11:28:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:55 INFO - 'PWD': '/builds/slave/test', 11:28:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:55 INFO - 'SHELL': '/bin/bash', 11:28:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:28:55 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:28:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:55 INFO - 'USER': 'cltbld', 11:28:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:55 INFO - 'XPC_FLAGS': '0x0', 11:28:55 INFO - 'XPC_SERVICE_NAME': '0', 11:28:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:28:55 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 11:28:55 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 11:28:55 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 11:28:55 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 11:28:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:28:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:28:57 INFO - Installing collected packages: mozsystemmonitor 11:28:57 INFO - Running setup.py install for mozsystemmonitor 11:28:57 INFO - Successfully installed mozsystemmonitor 11:28:57 INFO - Cleaning up... 11:28:57 INFO - Return code: 0 11:28:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:28:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:28:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:28:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:57 INFO - 'HOME': '/Users/cltbld', 11:28:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:57 INFO - 'LOGNAME': 'cltbld', 11:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:57 INFO - 'MOZ_NO_REMOTE': '1', 11:28:57 INFO - 'NO_EM_RESTART': '1', 11:28:57 INFO - 'PAGER': '/bin/cat', 11:28:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:57 INFO - 'PWD': '/builds/slave/test', 11:28:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:57 INFO - 'SHELL': '/bin/bash', 11:28:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:28:57 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:28:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:57 INFO - 'USER': 'cltbld', 11:28:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:57 INFO - 'XPC_FLAGS': '0x0', 11:28:57 INFO - 'XPC_SERVICE_NAME': '0', 11:28:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:57 INFO - Downloading/unpacking jsonschema==2.5.1 11:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:28:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:28:59 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 11:28:59 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 11:28:59 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 11:28:59 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 11:28:59 INFO - Downloading functools32-3.2.3-2.tar.gz 11:28:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:28:59 INFO - warning: no files found matching '*.txt' 11:28:59 INFO - no previously-included directories found matching 'build' 11:28:59 INFO - no previously-included directories found matching 'dist' 11:28:59 INFO - no previously-included directories found matching '.git*' 11:28:59 INFO - Installing collected packages: jsonschema, functools32 11:28:59 INFO - Running setup.py install for functools32 11:28:59 INFO - warning: no files found matching '*.txt' 11:28:59 INFO - no previously-included directories found matching 'build' 11:28:59 INFO - no previously-included directories found matching 'dist' 11:28:59 INFO - no previously-included directories found matching '.git*' 11:28:59 INFO - Successfully installed jsonschema functools32 11:28:59 INFO - Cleaning up... 11:28:59 INFO - Return code: 0 11:28:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:28:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:28:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:28:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:28:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:59 INFO - 'HOME': '/Users/cltbld', 11:28:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:59 INFO - 'LOGNAME': 'cltbld', 11:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:59 INFO - 'MOZ_NO_REMOTE': '1', 11:28:59 INFO - 'NO_EM_RESTART': '1', 11:28:59 INFO - 'PAGER': '/bin/cat', 11:28:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:59 INFO - 'PWD': '/builds/slave/test', 11:28:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:59 INFO - 'SHELL': '/bin/bash', 11:28:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:28:59 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:28:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:59 INFO - 'USER': 'cltbld', 11:28:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:59 INFO - 'XPC_FLAGS': '0x0', 11:28:59 INFO - 'XPC_SERVICE_NAME': '0', 11:28:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:29:00 INFO - Cleaning up... 11:29:00 INFO - Return code: 0 11:29:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:29:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:29:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:29:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:29:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:29:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:00 INFO - 'HOME': '/Users/cltbld', 11:29:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:00 INFO - 'LOGNAME': 'cltbld', 11:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:00 INFO - 'MOZ_NO_REMOTE': '1', 11:29:00 INFO - 'NO_EM_RESTART': '1', 11:29:00 INFO - 'PAGER': '/bin/cat', 11:29:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:00 INFO - 'PWD': '/builds/slave/test', 11:29:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:00 INFO - 'SHELL': '/bin/bash', 11:29:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:29:00 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:29:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:00 INFO - 'USER': 'cltbld', 11:29:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:00 INFO - 'XPC_FLAGS': '0x0', 11:29:00 INFO - 'XPC_SERVICE_NAME': '0', 11:29:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:00 INFO - Downloading/unpacking blobuploader==1.2.4 11:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:01 INFO - Downloading blobuploader-1.2.4.tar.gz 11:29:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:29:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:29:01 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 11:29:01 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 11:29:01 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 11:29:01 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 11:29:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:29:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:02 INFO - Downloading docopt-0.6.1.tar.gz 11:29:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:29:02 INFO - Installing collected packages: blobuploader, requests, docopt 11:29:02 INFO - Running setup.py install for blobuploader 11:29:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:29:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:29:02 INFO - Running setup.py install for requests 11:29:03 INFO - Running setup.py install for docopt 11:29:03 INFO - Successfully installed blobuploader requests docopt 11:29:03 INFO - Cleaning up... 11:29:03 INFO - Return code: 0 11:29:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:03 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')]} 11:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:03 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')]} 11:29:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:03 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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:29:03 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 11:29:03 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 11:29:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:29:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:03 INFO - 'HOME': '/Users/cltbld', 11:29:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:03 INFO - 'LOGNAME': 'cltbld', 11:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:03 INFO - 'MOZ_NO_REMOTE': '1', 11:29:03 INFO - 'NO_EM_RESTART': '1', 11:29:03 INFO - 'PAGER': '/bin/cat', 11:29:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:03 INFO - 'PWD': '/builds/slave/test', 11:29:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:03 INFO - 'SHELL': '/bin/bash', 11:29:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:29:03 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:29:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:03 INFO - 'USER': 'cltbld', 11:29:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:03 INFO - 'XPC_FLAGS': '0x0', 11:29:03 INFO - 'XPC_SERVICE_NAME': '0', 11:29:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:29:03 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-FUtcyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-e8IRsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-X1vIr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-veyYNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-2CTUNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-QN1YuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-pxer77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-dPjMlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-CeL9Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-IbL7CU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:29:04 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-ct4MuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:29:05 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-BasHWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:29:05 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-nGQM1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:29:05 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-o1gLSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:05 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-4cr6wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:29:05 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-onUCXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:29:05 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-NAndZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:29:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:29:05 INFO - Running setup.py install for manifestparser 11:29:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:29:05 INFO - Running setup.py install for mozcrash 11:29:05 INFO - Running setup.py install for mozdebug 11:29:06 INFO - Running setup.py install for mozdevice 11:29:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozfile 11:29:06 INFO - Running setup.py install for mozhttpd 11:29:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozinfo 11:29:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozInstall 11:29:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozleak 11:29:06 INFO - Running setup.py install for mozlog 11:29:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Running setup.py install for moznetwork 11:29:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Running setup.py install for mozprocess 11:29:07 INFO - Running setup.py install for mozprofile 11:29:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Running setup.py install for mozrunner 11:29:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Running setup.py install for mozscreenshot 11:29:07 INFO - Running setup.py install for moztest 11:29:08 INFO - Running setup.py install for mozversion 11:29:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:29:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:29:08 INFO - Cleaning up... 11:29:08 INFO - Return code: 0 11:29:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:08 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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:29:08 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 11:29:08 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 11:29:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:29:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:08 INFO - 'HOME': '/Users/cltbld', 11:29:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:08 INFO - 'LOGNAME': 'cltbld', 11:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:08 INFO - 'MOZ_NO_REMOTE': '1', 11:29:08 INFO - 'NO_EM_RESTART': '1', 11:29:08 INFO - 'PAGER': '/bin/cat', 11:29:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:08 INFO - 'PWD': '/builds/slave/test', 11:29:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:08 INFO - 'SHELL': '/bin/bash', 11:29:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:29:08 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:29:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:08 INFO - 'USER': 'cltbld', 11:29:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:08 INFO - 'XPC_FLAGS': '0x0', 11:29:08 INFO - 'XPC_SERVICE_NAME': '0', 11:29:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:29:08 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-JsycF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:29:08 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Q6yTe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:29:08 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-mIgZYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:29:08 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-x5j595-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-8jkun4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-FwWbYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-3ac8Zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-pAiEbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-yRBMAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-ENFp1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-rO_IVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-qlIYFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-lcjUMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:29:09 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-1XEv6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:29:10 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)) 11:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:10 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-c7r0lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:10 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)) 11:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:29:10 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-q5NLaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:29:10 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)) 11:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:29:10 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-kR2Cn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:29:10 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)) 11:29:10 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)) 11:29:10 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)) 11:29:10 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)) 11:29:10 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)) 11:29:10 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)) 11:29:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:29:10 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 11:29:10 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 11:29:10 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 11:29:10 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 11:29:11 INFO - Downloading blessings-1.6.tar.gz 11:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:29:11 INFO - Installing collected packages: blessings 11:29:11 INFO - Running setup.py install for blessings 11:29:12 INFO - Successfully installed blessings 11:29:12 INFO - Cleaning up... 11:29:12 INFO - Return code: 0 11:29:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:29:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:29:12 INFO - Reading from file tmpfile_stdout 11:29:12 INFO - Current package versions: 11:29:12 INFO - blessings == 1.6 11:29:12 INFO - blobuploader == 1.2.4 11:29:12 INFO - docopt == 0.6.1 11:29:12 INFO - functools32 == 3.2.3-2 11:29:12 INFO - jsonschema == 2.5.1 11:29:12 INFO - manifestparser == 1.1 11:29:12 INFO - mozInstall == 1.12 11:29:12 INFO - mozcrash == 1.0 11:29:12 INFO - mozdebug == 0.1 11:29:12 INFO - mozdevice == 0.49 11:29:12 INFO - mozfile == 1.2 11:29:12 INFO - mozhttpd == 0.7 11:29:12 INFO - mozinfo == 0.9 11:29:12 INFO - mozleak == 0.1 11:29:12 INFO - mozlog == 3.4 11:29:12 INFO - moznetwork == 0.27 11:29:12 INFO - mozprocess == 0.25 11:29:12 INFO - mozprofile == 0.28 11:29:12 INFO - mozrunner == 6.13 11:29:12 INFO - mozscreenshot == 0.1 11:29:12 INFO - mozsystemmonitor == 0.3 11:29:12 INFO - moztest == 0.8 11:29:12 INFO - mozversion == 1.4 11:29:12 INFO - psutil == 3.1.1 11:29:12 INFO - requests == 1.2.3 11:29:12 INFO - wsgiref == 0.1.2 11:29:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29: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')]} 11:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29: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')]} 11:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:12 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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:29:12 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 11:29:12 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 11:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:12 INFO - 'HOME': '/Users/cltbld', 11:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:12 INFO - 'LOGNAME': 'cltbld', 11:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:12 INFO - 'MOZ_NO_REMOTE': '1', 11:29:12 INFO - 'NO_EM_RESTART': '1', 11:29:12 INFO - 'PAGER': '/bin/cat', 11:29:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:12 INFO - 'PWD': '/builds/slave/test', 11:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:12 INFO - 'SHELL': '/bin/bash', 11:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:29:12 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:12 INFO - 'USER': 'cltbld', 11:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:12 INFO - 'XPC_FLAGS': '0x0', 11:29:12 INFO - 'XPC_SERVICE_NAME': '0', 11:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 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)) 11:29:12 INFO - Cleaning up... 11:29:12 INFO - Return code: 0 11:29:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:29: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')]} 11:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29: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')]} 11:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:12 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 0x10a742b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a745768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb25b94f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, '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.xASM1xf6B3/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.rcINlBPyID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:29:12 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 11:29:12 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 11:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:12 INFO - 'HOME': '/Users/cltbld', 11:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:12 INFO - 'LOGNAME': 'cltbld', 11:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:12 INFO - 'MOZ_NO_REMOTE': '1', 11:29:12 INFO - 'NO_EM_RESTART': '1', 11:29:12 INFO - 'PAGER': '/bin/cat', 11:29:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:12 INFO - 'PWD': '/builds/slave/test', 11:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:12 INFO - 'SHELL': '/bin/bash', 11:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:29:12 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:12 INFO - 'USER': 'cltbld', 11:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:12 INFO - 'XPC_FLAGS': '0x0', 11:29:12 INFO - 'XPC_SERVICE_NAME': '0', 11:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:29:12 INFO - Cleaning up... 11:29:12 INFO - Return code: 0 11:29:12 INFO - Running post-action listener: _resource_record_post_action 11:29:12 INFO - Running post-action listener: _start_resource_monitoring 11:29:12 INFO - Starting resource monitoring. 11:29:12 INFO - [mozharness: 2017-05-15 18:29:12.905878Z] Finished create-virtualenv step (success) 11:29:12 INFO - [mozharness: 2017-05-15 18:29:12.906830Z] Running install step. 11:29:12 INFO - Running pre-action listener: _resource_record_pre_action 11:29:12 INFO - Running main action method: install 11:29:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:29:13 INFO - Reading from file tmpfile_stdout 11:29:13 INFO - Detecting whether we're running mozinstall >=1.0... 11:29:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:29:13 INFO - Reading from file tmpfile_stdout 11:29:13 INFO - Output received: 11:29:13 INFO - Usage: mozinstall [options] installer 11:29:13 INFO - Options: 11:29:13 INFO - -h, --help show this help message and exit 11:29:13 INFO - -d DEST, --destination=DEST 11:29:13 INFO - Directory to install application into. [default: 11:29:13 INFO - "/builds/slave/test"] 11:29:13 INFO - --app=APP Application being installed. [default: firefox] 11:29:13 INFO - mkdir: /builds/slave/test/build/application 11:29:13 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'] 11:29:13 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 11:29:28 INFO - Reading from file tmpfile_stdout 11:29:28 INFO - Output received: 11:29:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:29:28 INFO - Running post-action listener: _resource_record_post_action 11:29:28 INFO - [mozharness: 2017-05-15 18:29:28.076956Z] Finished install step (success) 11:29:28 INFO - [mozharness: 2017-05-15 18:29:28.077080Z] Running run-tests step. 11:29:28 INFO - Running pre-action listener: _resource_record_pre_action 11:29:28 INFO - Running main action method: run_tests 11:29:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:29:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:29:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:29:28 INFO - 2017-05-15 11:29:28.091 screenresolution[1636:7586] starting screenresolution argv=screenresolution get 11:29:28 INFO - 2017-05-15 11:29:28.094 screenresolution[1636:7586] Display 0: 1600x1200x32@60 11:29:28 INFO - 2017-05-15 11:29:28.100 screenresolution[1637:7588] starting screenresolution argv=screenresolution list 11:29:28 INFO - Available Modes on Display 0 11:29:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:29:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:29:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:29:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:29:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:29:28 INFO - Intel Iris: 11:29:28 INFO - Chipset Model: Intel Iris 11:29:28 INFO - Type: GPU 11:29:28 INFO - Bus: Built-In 11:29:28 INFO - VRAM (Dynamic, Max): 1536 MB 11:29:28 INFO - Vendor: Intel (0x8086) 11:29:28 INFO - Device ID: 0x0a2e 11:29:28 INFO - Revision ID: 0x0009 11:29:28 INFO - Displays: 11:29:28 INFO - Display: 11:29:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:29:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:29:28 INFO - Display Serial Number: 1600x1200 60 11:29:28 INFO - Main Display: Yes 11:29:28 INFO - Mirror: Off 11:29:28 INFO - Online: Yes 11:29:28 INFO - Rotation: Supported 11:29:28 INFO - Return code: 0 11:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:29:28 INFO - Python 2.7.3 11:29:28 INFO - Return code: 0 11:29:28 INFO - grabbing minidump binary from tooltool 11:29:28 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')]} 11:29:28 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 0x10a520d30>, '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 0x10a447db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a515be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:29:28 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 11:29:28 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 11:29:28 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 11:29:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:29:28 INFO - Return code: 0 11:29:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:29:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:29:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:29:28 INFO - ENV: RUST_BACKTRACE is now 1 11:29:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:29:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:29:28 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/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0370', '--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 11:29:28 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/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0370 --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 11:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xASM1xf6B3/Render', 11:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:28 INFO - 'HOME': '/Users/cltbld', 11:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:28 INFO - 'LOGNAME': 'cltbld', 11:29:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:29:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:28 INFO - 'MOZ_NO_REMOTE': '1', 11:29:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:29:28 INFO - 'NO_EM_RESTART': '1', 11:29:28 INFO - 'PAGER': '/bin/cat', 11:29:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:28 INFO - 'PWD': '/builds/slave/test', 11:29:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:28 INFO - 'RUST_BACKTRACE': '1', 11:29:28 INFO - 'SHELL': '/bin/bash', 11:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rcINlBPyID/Listeners', 11:29:28 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:28 INFO - 'USER': 'cltbld', 11:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:28 INFO - 'XPC_FLAGS': '0x0', 11:29:28 INFO - 'XPC_SERVICE_NAME': '0', 11:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:28 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/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0370', '--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 11:29:28 INFO - mozversion application_buildid: 20170515095425 11:29:28 INFO - mozversion application_changeset: 241a875b457fd4164cfe96f57865dd0969f20909 11:29:28 INFO - mozversion application_display_name: Firefox 11:29:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:29:28 INFO - mozversion application_name: Firefox 11:29:28 INFO - mozversion application_remotingname: firefox 11:29:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:29:28 INFO - mozversion application_vendor: Mozilla 11:29:28 INFO - mozversion application_version: 54.0 11:29:28 INFO - mozversion platform_buildid: 20170515095425 11:29:28 INFO - mozversion platform_changeset: 241a875b457fd4164cfe96f57865dd0969f20909 11:29:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:29:28 INFO - mozversion platform_version: 54.0 11:29:28 INFO - using testdate: 1494872968 11:29:28 INFO - actual date: 1494872968 11:29:28 INFO - starting webserver on 'localhost:49219' 11:29:28 INFO - SUITE-START | Running 2 tests 11:29:28 INFO - TEST-START | dromaeo_css 11:29:28 INFO - operating with platform_type : mac_ 11:29:28 INFO - Initialising browser for dromaeo_css test... 11:29:28 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmpgs6IQF/profile http://localhost:49219/getInfo.html) 11:29:30 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 11:29:30 INFO - PROCESS | 1647 | colorDepth:24 11:29:30 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 11:29:30 INFO - PROCESS | 1647 | __metrics 11:29:31 INFO - PROCESS | 1647 | 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" 11:29:31 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:29:31 INFO - TEST-INFO | 1647: exit 0 11:29:31 INFO - Browser initialized. 11:29:31 INFO - Running cycle 1/1 for dromaeo_css test... 11:29:31 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmpgs6IQF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:43 INFO - PROCESS | 1650 | RSS: Main: 164859904 11:29:43 INFO - PROCESS | 1650 | 11:32:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 11:32:49 INFO - PROCESS | 1650 | RSS: Main: 303235072 11:32:49 INFO - PROCESS | 1650 | 11:35:52 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 11:35:52 INFO - PROCESS | 1650 | RSS: Main: 301445120 11:35:52 INFO - PROCESS | 1650 | 11:38:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 11:38:55 INFO - PROCESS | 1650 | RSS: Main: 301731840 11:38:55 INFO - PROCESS | 1650 | 11:41:58 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 11:41:58 INFO - PROCESS | 1650 | RSS: Main: 286105600 11:41:58 INFO - PROCESS | 1650 | 11:45:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 11:45:03 INFO - PROCESS | 1650 | RSS: Main: 258543616 11:45:03 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 11:48:06 INFO - PROCESS | 1650 | RSS: Main: 418451456 11:48:06 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | __start_tp_report 11:48:06 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 11:48:06 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 11:48:06 INFO - PROCESS | 1650 | |i|pagename|runs| 11:48:06 INFO - PROCESS | 1650 | |0;dojo.html;3507.824608769561;3523.5067090607313;3523.665251801078;3540.964590354096;3586.479960405741;6207.410296021876;6215.4095360940755;6218.0362043883215;6250.062490626408;6268.561200715952;5609.943900560993;5844.94790937631;6198.59709118907;6271.749130034793;6284.308726040132;5460.126379779226;5514.972425137868;5547.972260138694;5556.722163891809;5644.6613203207735;5444.101723215685;5444.673319600817;5459.617826752129;5587.469190426903;5618.129189975561;5541;5547.44525547446;5554.444555544454;5565.582581306398;5579.191017302489;5606.103023516259;5611.382747897728;5614.943850561483;5625.662460252357;5626.85932851682;5381.973090134564;5491.395946445888;5541.473560011833;5550;5577.247071645316;6112.929053818819;6131.847187782227;6140.785072522441;6162.445379915829;6163.291221509539;5354.464553544653;6309.116723658645;6315.24217093947;6327.272363678144;6356.110144579762;6509.967450162766;6532.11816404784;6534.640594767286;6561.114249576294;6582.1114149589675;6276.717547710318;6311.558190926637;6315.052742088724;6320.5891617044745;6324.272708638566;6500.804975850732;6796.864062718817;6817.829554261183;6818.522703410813;6823.112995310677;6311.715972781236;6319.368063193644;6336.334684858116;6373.362663733589;6416.390442907917;6567.9671601641685;6588.374104460068;6603.636799975951;6611.966940165365;6623.536352455282;6231.750729970841;6368.267649220306;6443.742250309935;6451.903221451682;6572.34276572339;6308.4007019334;6311.779087731908;6319.178506794087;6323.20959880015;6323.715432805535;4912.680675756064;5288.709120998382;5339.599530035185;5340.519353258148;5346.7861285548925;4689.3903792507435;4692.131955588227;4695.600873925686;4701;4750.311204875274;5283.233931079972;6168.228971378578;6170;6248;6269.216347956505;6188.659623720649;6396.584222025554;6460.870782584322;6501.902471462932;6551.901721474182;6237.937620623736;6250.312465628777;6254.249490061131;6260.530460215502;6263.31103578606;5688.8862222755315;5791.218185544982;5819.185314056035;5839.124131380242;5843.0066888629035;6417.550771445954;6469.159009328757;6669.433098186709;6718.496112791658;6765.932340676531;6532.902006470002;6567.868642627216;6582.76960306387;6585.769498067544;6592.901106483502;6642.867142657216;6770.830729231809;6773.559718618274;6776.322367763184;6790.388865002174;6266.185395898504;6325.1144839721555;6371.585846919936;6482.643454610042;6486.708098135689;6555.934440655532;6567.27759946396;6577.769778057745;6600.273969863407;6614.173228346457;6371.74513019474;6374.649394283359;6374.93625063762;6375.394337537927;6382.936170638234;5344.064788661891;6254.3745625437095;6312.43188113072;6335.176427064452;6386.96806515983;5448.182772584144;6224.439800417984;6324.494040476843;6324.525660575383;6348.746050157942;6340.365963403623;6344.809655710336;6352.237731472253;6356.300806911144;6387.5209359297305;5451.809186678447;6112.26032301701;6140.631562106288;6154.446099851035;6175.6912154393 11:48:06 INFO - PROCESS | 1650 | |1;ext.html;779.7426849139781;800.8718605023196;821.5563595658343;904.0236544531906;911.1162172692486;29243.122706318816;41578.168436631284;41959.58040419595;42048.57951420485;42121.57878421215;42591;42832.357514637304;42850.785746071226;42887.35668964968;42975;7940.1265860755275;9560.617575296998;9719.17387022104;9738.415695058284;9766.36518626287;7980.920190798076;11595.94202028993;11834.940825295862;12013.879861201407;12018.51925922962;11762.23545469546;11863.881361186364;11864.644060678194;11867.228630139056;11872.347020913847;11762.235454695418;11792.233504822245;11826.172167948247;11837.644870653894;11846.348450835201;11561;11609.4195290236;11644.534218631245;11657.883421165849;11661.70845728855;40553;40667.389989150186;40706.796466017484;40779.38830917539;40795.14433989622;3987.8803635890968;3997.760134391946;4005.1589166274825;4006.5192176938954;4056.6349028587274;27520.862395687895;28235.15294541167;30182.09453716392;30646.38707225843;30652;3179.9046028619177;3228.1122691259857;3299.15871452778;3320.9833950830093;3438.5529881115544;265673.3432665687;337470;341931;342069;343380;52773;52827.20759188615;52838.679033024484;52852.73573632184;53081.93836123256;55471;55616;55619.887602248535;55627;55640.887182256934;15136.243187840564;15563.988340757815;15586.688266234838;15595.376184952702;15654.37382504687;11867.643970680721;13319.467221311234;13333.66665833362;13338.533151339656;13368.933155334162;10813.459327033617;10859.402732849769;12088.576899808464;12313.69215769595;12399.318037508023;5685.630434021776;5712.428757124338;5734.455226753452;5740.913886291709;5759.8560035999435;1150.3270586706703;1173.4660729368118;1181.1141643767176;1186.2822992089834;1207.8836601567934;25833.870830645727;26710.86644566765;26963.865180673973;26988.59517107245;27503.44993100167;953.7758626722854;1147.6270212181075;1157.5311998640564;1166.2011522107384;1315.6973896003972;15400;15433;15470.143582769915;15551.844481555267;15810.60473488172;457521;457824;459880;460300;460915;60141;60325;60353.79292414127;60568.69715651393;60731.39268607346;1459.6204986703322;1476.8596983286786;1499.642823244965;1502.6543894904014;1506.9698606027819;27505.724942751116;28016.439671206055;28774.568381474714;29369;29394;48552.02895942132;48642;48691;48740.512594875014;48834.26748598845;437994.62005380815;442111;442191;442297;442403;32340.514892277104;33094.00717978465;33129.83435082809;33239.667603324626;33262.66737332693;31689.683103168674;35935.281294373446;39785.60214397819;48188.79528011828;49192.508074920224;31068.689313107483;31364;31842.73470201334;31886.6811331893;32667.836660817495;26569.867150664122;30951.226219344695;30970.84514577413;31272.843635781675;32277.838610806797;37327.6267237335;38413.23173536458;38715.612843871204;38721.806390968995;38729.6127038726;30320.84839575788;30796.07611771561;31263.68736312699;32507.512387313735;32998.67001329956;23912.521749565258;25207.49585008326;25286.7362026932;25669.614955775305;25690.100846470283 11:48:06 INFO - PROCESS | 1650 | |2;jquery.html;419.73450048104957;420.91160856220193;428.56714718134674;438.08160343593414;440.1351344607845;19578.70631940522;20782.792172078276;21746.238881639147;21973.450663733416;22040;21761.129554817788;22312.21907233247;22578.54842903141;22878.65682014771;23895;6773.4242589379965;6878.3465570770695;7124.073870396841;7163.570185788869;7166.462515311347;6928.9307106929045;6996.545224560387;7053.400460960799;7053.506254562183;7055.470839687018;7040.331168538981;7059.2234854166;7479;7789.76630701077;7962.5620590867475;7623.656935437919;7813.960930195312;7884.448088633798;7887.763367098996;7951.642176102089;7658.502197357154;7660.463767536275;7666;7679.462437629368;7679.769606911802;23411.94646240906;25649.74350256492;26123.563204023772;26621;27485.587716184273;2950.498415269409;2970.1386597886585;2970.732634062923;2982.2991596974693;2993.745531629814;17743.11284435773;17828.286868525243;18290.08549572516;18495.075246237768;19237.23051077955;1958.1971391729464;1959.6864501679806;1961.7038685279335;2024.5545979884403;2029.4723371923258;134412;148072;160382.19808900997;161017;165823;23831;24859.5028099437;24885.5022899541;25080.37299067538;25143.497130057298;41713;41828.790856045525;42940.78529607332;42971.78514107472;43038.78480607577;8788.340874434443;9734.172595329335;10583.682489525327;10765;10932.344059356465;8201.343892488587;8960.283177345797;8999.955000225087;9017.684381046633;9182.586783594754;8856.822863542822;9638.277129215337;9781.266405019653;9803.754906127262;9818.607255709692;4057.715959882839;4110.479043401069;4134.214499245134;4165.520965088993;4302.5586428546785;1073.613499140309;1078.9892101079047;1099.1261946752352;1102.1293178388983;1149.9137564682683;22574.887125564597;22584.774152258597;22915.968781404878;23024.309270721904;23158.42103947415;1081.9296745711504;1100.6753007862699;1105.5688281570194;1126.8478755367921;1127.577158565538;11081.168912331608;11566.537338506534;11793.88206117945;11840;11853.170278080452;746788;758464;762227;763036;764563;158950;160458;161149.52528085638;161455;167795;1211.9697007574705;1240.2496489623647;1262.9936850315873;1271.5867343113525;1273.2233337664202;19194.424167275003;19497.70753438728;19888;19901.900490497454;19957.60084798325;60051;61527;61609;62294.688526557075;62971.685141574;84564.57717711372;84869.5756521238;86809;88663;90278;11227.663170104583;11373.43132843338;11685.422467967055;11733.00269477104;11803.350815705218;12462.750744985231;12543.874561254635;12632.178908371294;12857.678558036125;12875.484980601046;10653.786924261627;10800.945995269973;10987.450627468435;11135.777284454103;11256.437178140897;10205.795884082128;10271.332363396356;10378.27352085377;10558.472076395996;10606.522706478072;11184.776304474028;11215;11281.548738050386;11427.657170284905;11495.31028138281;11443.942780286045;11451.59919402817;11788.29270243788;11798.410079496163;11804.586839460577;10976.670699879016;11006.504707288032;11125.499352528994;11131.499082541142;11246.887531124585 11:48:06 INFO - PROCESS | 1650 | |3;mootools.html;1394.4491925689347;1463.7218928403606;1529.4952665620347;1579.399828065335;1622.2699785096709;9350.579223934923;9755;11011.449427528632;11288.379139147344;11293.661190164288;9488.430694158355;11131.888681113187;11247.437628118581;11253.774924501526;11267.60563380282;2429.2590759818204;2480.231128350208;2485.5898776701824;2486.9005239790386;2487;2087.561612061469;2156.6872803443493;2160;2354.5526349993447;2391.5097405031925;2454.2759885833634;2477.6531285620026;2488.415222422729;2489.0043982407;2492.675952126221;2422.4549476367943;2440.7803297703203;2449.167283123729;2450.5711500487523;2450.791682706972;2418.9879050604636;2434.0628857889665;2437.756224377566;2442;2443.6334549817493;9331.76670583234;11102.833457498064;11191.44042797865;11281.774364512747;11283.984441400313;5922.3485416604135;6099.420555047263;6103.206583144163;6105.786297479567;6117.755289788403;3635.2366003139373;3708.054446116223;3768.321702093622;3789.3179227738997;3843.961560384388;1283.57641978147;1598.8161361443467;1922.7308176855252;1948.1915005272795;2034.5218873564847;15921;15991.840081599152;16011.119388433632;16036.198190090565;16045.277962491598;4721.740304283228;4850.95149048505;4874.293227481995;4916.188828843201;4941.604671626261;11989.040876729841;11992.520299188109;12012.459439325252;12022;12023.338716370683;6025.789097381658;6034.758609655652;6039.214902062704;6040.275166979991;6043.184170136975;5209.218617207449;5411.512963833274;5418.160185171305;5480.91778623321;5509.696966666792;3877.728559000899;3967.3652215645357;4013.177298653769;4046.4132700758814;4060.7563546187325;848.3722045686147;978.7748817771952;1057.3233130796295;1057.7778666479971;1059.0786016165985;960.1023043454253;976.3360914578013;979.7501637082498;988.7083310423442;1019.7552587378975;6421.29365769765;6602.415420299094;6632.2704502504685;6714.832129196711;6819.386255237053;1018.8064267789097;1026.9640562580273;1043.113353649392;1044.4412239451856;1062.4953147254992;4249.723767955073;4317.870463886088;4355.937783719618;4448.177087238806;4498.775061246924;13709.862901371058;13983.580492585239;14020.85979140216;14409.279536023158;14427.92786036063;13887.58337249884;14148.221847798264;14333.06835055718;14359.641008974859;14368.497102601359;10298.17614590846;10375.74060648505;10426.218033647507;10517.316374435637;10524.736881577868;6562.77030303937;6639.468842492685;6706.228783689743;6745.5952642841585;6770.763023294161;12349.75300494003;12387.132900696864;12423.62729118128;12635.557755478514;12676.809847852473;12724.68188295263;12786.168899021533;12789.488420463449;12937.223766574036;12960.287184204959;8474.576271186539;8525.87211191845;8628.784280392789;8676.305895528216;8693.217610415093;10244.385336879513;10337.896621033693;10439.843402348819;10443.321184123008;10548.419836909045;8845.69040083594;8903.19871211594;8946.37375383717;8972;9027.187553120251;8630.266427353745;8744.169303916118;8768.868466973128;8967.775805604912;8992.415492992934;7895.171007044178;7967.521948683097;7988.800279993046;8087.63605637736;8116.918830811853;5737.770489180386;5884.440978107073;6005.819825405245;6049.364816694359;6196.81409557714;5849.239598852292;6001.5798894077;6074;6118.112873633388;6125.785597504244 11:48:06 INFO - PROCESS | 1650 | |4;prototype.html;354.9751517393782;373.7925451374486;387.7576514678326;391.48519696598987;396.5558574396676;5244.423113457517;5272.261883336336;5286.863324385256;5311.468853114687;5326.813561525338;4305.526392096866;5027.773750181242;5069.3156423882765;5077.41609714882;5153.948460515394;3709.981450092746;4248.511421186572;4278.871633850989;4282.507511636155;4306.913861722764;3854.4025676020124;4324.740515569076;4329.913401731964;4346.652267818583;4359.17175736609;3964.8612298569556;4179.414881916534;4214.093969796493;4268.807903644328;4313.805878735465;4182.91634167318;4248.916526285778;4261.126469073833;4262.488501379854;4270.210011147932;3967.2462232175794;3983.4224037514687;3983.7609743415487;4003.5195776506716;4004.739691920016;4196.013936724868;4496.55034496551;4699.7415142167165;4984.900301993976;5086.694798312114;3924.13668992824;4421.784009397409;4454.532274111237;4843.564079232886;4895.216765317532;4951.752412379403;5273.604479664041;5326.946730532683;5388.973055134699;5427.530473634526;3626.709863210937;3901.395283731026;4042.5957404259634;4236.131593023453;4408.801603927831;5006.997201119581;5563.499285064364;5597.888042239132;5699.202111704365;5812.447817457335;3785.7917814519915;3918.5885482024546;4182.644475219579;4402.669799765031;4501.369808226851;4157.503298812425;4624.884377890579;4652.395188625457;4654.604358629486;4688.530487439339;4382.748733728278;4656.906861862743;4727.84167878865;4738.573528382462;4923.735081862384;4707.340972263886;5065.860181459216;5378.435264297271;5456.672599644034;5491.517290331668;1589.4595837415334;1624.951251462458;1660.01229268585;1662.4597005972864;1712.4862541237578;1230.913836031487;1242.9012163728914;1254.5671743248565;1407.7677183264852;1428.250168661461;4778.474367819537;4842.143135550043;5102.469259222218;5122.308488354099;5144.279800827887;5143.691378517226;5698.601097923106;5699.924553943867;5741.368449470555;5750.137479378127;4521.276595744665;4560.0463367463935;4596.517365676623;4699.624030077585;4868.342773725572;2542.720300766914;2914.329704168053;2941.352902361477;2942.6321709786275;2957.452871218831;4876.003773038738;5260.658532074297;5849.771547974888;5874.706264686749;5880.970595147082;4976.90046199074;5106.212825511041;5322.650736010285;5398.676079435247;5726.828195154152;5598.552115830724;5649.350324712628;5818.796342128006;5820.224831426371;6073.240844894388;4672.953270467252;5005.324281221989;5583.277140930579;5683.516901063456;5732.57005724564;4735.313379559876;5381.946180538144;5535.335759708861;5545.251391062155;5559.582306511814;4932.8520144395725;5480.424555421623;5546.58400619947;5691.288588926384;5805.738741756547;4228.767417792051;4688.437387513521;4836;4850.7817148227705;4883.682560633548;4911.754412279301;5008.6744361616375;5536.197251398606;5579.995600791896;5633.887322253614;4400.867973960786;4990.378127108632;5408.486193811582;5467.343918729778;5483.367983368085;4927.802887884444;5478.863028424321;5516.531094856982;5549.500544950813;5615.578831587565;4201.810918508613;4663.906721865612;4898.681585696918;5035.658418835259;5069.5944324454695;5512.39363670004;5549.334079910436;5550.58370622213;5579.158877570473;5631.549476041825;4988.65306367286;5315.521603055744;5458.181272808952;5459.6250630975655;5474.91787623194 11:48:06 INFO - PROCESS | 1650 | |5;yui.html;768.5427170833356;787.5117427195137;826.9545175015379;884.8893888263967;887.6871921549525;16359.754603680954;17345.219465124068;17396.043217623024;17400.477985660436;17402.82597174028;14039;14046.08700434471;14119.576412707609;14211;14288.642783930381;960.0927123867913;978.848278516831;979.9853002204973;981.2542467724545;982.7837875667377;812.8130529978108;974.1914211204684;976;982.8722266105432;1000.194843151263;1043.7338478687961;1048.5019615682565;1048.5124417146026;1048.9318194317345;1049.6746008737277;1042.6090216168936;1044.639599338227;1044.8067107585084;1045.7124290820009;1048.6434612231878;769.7190525458168;772.8029352515073;773.0568706178453;773.1186447449911;774.3611520495614;16734.24695888688;17039.403620873334;17067;17640.38258660941;17923.820761792475;14540.200288984102;14583.708325833424;14662.560123196321;14710.926445367812;14789.334479948428;11403.3728144952;11700.707482312926;11790.35153066581;11891.643250702493;11991.520339186422;3789.2989796887387;3815.904602384963;3848.422736589506;3896.2597106549665;3990.7805070721097;111583.88416115816;112536.4373178137;113108;113481.86518134795;114829;10713.732156696145;10779.353238805697;11009.559617615367;11340;11364.431778411212;21306.893465532885;21686.915654217228;21714.457138571663;21894.343169704935;22025.66961495579;6384.648844313608;7874.842503149905;7903.604819759104;7907.406944479187;7920.089189743138;3940.172563761585;4328.502222244482;4382.846600368972;4529.184746745552;4680.9297860532115;3485.4423292273113;3587.2466781975554;3590.2281009583066;3592.856285748593;3593.2454184621524;620.6183197333697;632.2128949457863;690.3062422265591;746.6975874770726;747.3274053352027;688.435482904024;690.7927621713466;711.8398360368875;714.1465948191861;743.0266351080103;8088.555129467818;10158.593656253815;10223.539940702687;10467.633632822815;10711.410872401937;694.3646563394437;704.7125161123405;706.9575825450489;707.5789905006616;719.6150059718026;4881.877953051202;5382.407935127133;5545.279113715191;5663.292088488939;5731.229229269129;108197;109304.81390372149;110565.44717276363;111172;114188;58929.116063258225;80623.59688201522;83610;83636.58181709053;86726.1327386718;80568;82442;82858.58570707108;82924;84477.57761211155;9911.603535858774;10032.197424206191;10886.074683651977;11273.436328183394;11416.94291528537;44929.77535112303;45595.08809823851;51581.226281606556;51752.74123629358;52466.213006804166;64826;65851;66927.9960800598;67870.66064669644;69593.30406695868;11704.824427633423;13244.337783110614;13437.932810335886;13745;13979.440822367398;15112.471063512725;15310.234488275319;15465.304061317043;15786.842131578996;15981.840181598036;14119.858801411854;14266.500672476828;14416.495422660157;14542.127472351693;14609.415623375371;14045.648858778612;14142.717145657234;14233.012019516626;14467.421303147756;14668.046576972463;13223.471061157446;13390.732185356434;13883.236421996888;13937.30313484342;14185.432582696576;9614.51927403613;9739.026097390204;9801.558929848317;10285.382877027403;10341.586336546454;10943.726406839893;11036.503357348778;11333.022767144701;11402;11420.771584568429 11:48:06 INFO - PROCESS | 1650 | __end_tp_report 11:48:06 INFO - PROCESS | 1650 | __start_cc_report 11:48:06 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1517 11:48:06 INFO - PROCESS | 1650 | __end_cc_report 11:48:06 INFO - PROCESS | 1650 | __startTimestamp1494874086565__endTimestamp 11:48:06 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | ------- Summary: start ------- 11:48:06 INFO - PROCESS | 1650 | Number of tests: 6 11:48:06 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:6044.22 Median:6276.72 stddev:659.38 (10.5%) stddev-sans-first:630.67 11:48:06 INFO - PROCESS | 1650 | Values: 3507.8 3523.5 3523.7 3541.0 3586.5 6207.4 6215.4 6218.0 6250.1 6268.6 5609.9 5844.9 6198.6 6271.7 6284.3 5460.1 5515.0 5548.0 5556.7 5644.7 5444.1 5444.7 5459.6 5587.5 5618.1 5541.0 5547.4 5554.4 5565.6 5579.2 5606.1 5611.4 5614.9 5625.7 5626.9 5382.0 5491.4 5541.5 5550.0 5577.2 6112.9 6131.8 6140.8 6162.4 6163.3 5354.5 6309.1 6315.2 6327.3 6356.1 6510.0 6532.1 6534.6 6561.1 6582.1 6276.7 6311.6 6315.1 6320.6 6324.3 6500.8 6796.9 6817.8 6818.5 6823.1 6311.7 6319.4 6336.3 6373.4 6416.4 6568.0 6588.4 6603.6 6612.0 6623.5 6231.8 6368.3 6443.7 6451.9 6572.3 6308.4 6311.8 6319.2 6323.2 6323.7 4912.7 5288.7 5339.6 5340.5 5346.8 4689.4 4692.1 4695.6 4701.0 4750.3 5283.2 6168.2 6170.0 6248.0 6269.2 6188.7 6396.6 6460.9 6501.9 6551.9 6237.9 6250.3 6254.2 6260.5 6263.3 5688.9 5791.2 5819.2 5839.1 5843.0 6417.6 6469.2 6669.4 6718.5 6765.9 6532.9 6567.9 6582.8 6585.8 6592.9 6642.9 6770.8 6773.6 6776.3 6790.4 6266.2 6325.1 6371.6 6482.6 6486.7 6555.9 6567.3 6577.8 6600.3 6614.2 6371.7 6374.6 6374.9 6375.4 6382.9 5344.1 6254.4 6312.4 6335.2 6387.0 5448.2 6224.4 6324.5 6324.5 6348.7 6340.4 6344.8 6352.2 6356.3 6387.5 5451.8 6112.3 6140.6 6154.4 6175.7 11:48:06 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:55905.70 Median:32424.01 stddev:109500.14 (337.7%) stddev-sans-first:109729.10 11:48:06 INFO - PROCESS | 1650 | Values: 779.7 800.9 821.6 904.0 911.1 29243.1 41578.2 41959.6 42048.6 42121.6 42591.0 42832.4 42850.8 42887.4 42975.0 7940.1 9560.6 9719.2 9738.4 9766.4 7980.9 11595.9 11834.9 12013.9 12018.5 11762.2 11863.9 11864.6 11867.2 11872.3 11762.2 11792.2 11826.2 11837.6 11846.3 11561.0 11609.4 11644.5 11657.9 11661.7 40553.0 40667.4 40706.8 40779.4 40795.1 3987.9 3997.8 4005.2 4006.5 4056.6 27520.9 28235.2 30182.1 30646.4 30652.0 3179.9 3228.1 3299.2 3321.0 3438.6 265673.3 337470.0 341931.0 342069.0 343380.0 52773.0 52827.2 52838.7 52852.7 53081.9 55471.0 55616.0 55619.9 55627.0 55640.9 15136.2 15564.0 15586.7 15595.4 15654.4 11867.6 13319.5 13333.7 13338.5 13368.9 10813.5 10859.4 12088.6 12313.7 12399.3 5685.6 5712.4 5734.5 5740.9 5759.9 1150.3 1173.5 1181.1 1186.3 1207.9 25833.9 26710.9 26963.9 26988.6 27503.4 953.8 1147.6 1157.5 1166.2 1315.7 15400.0 15433.0 15470.1 15551.8 15810.6 457521.0 457824.0 459880.0 460300.0 460915.0 60141.0 60325.0 60353.8 60568.7 60731.4 1459.6 1476.9 1499.6 1502.7 1507.0 27505.7 28016.4 28774.6 29369.0 29394.0 48552.0 48642.0 48691.0 48740.5 48834.3 437994.6 442111.0 442191.0 442297.0 442403.0 32340.5 33094.0 33129.8 33239.7 33262.7 31689.7 35935.3 39785.6 48188.8 49192.5 31068.7 31364.0 31842.7 31886.7 32667.8 26569.9 30951.2 30970.8 31272.8 32277.8 37327.6 38413.2 38715.6 38721.8 38729.6 30320.8 30796.1 31263.7 32507.5 32998.7 23912.5 25207.5 25286.7 25669.6 25690.1 11:48:06 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:44207.42 Median:22576.72 stddev:126643.21 (560.9%) stddev-sans-first:126955.80 11:48:06 INFO - PROCESS | 1650 | Values: 419.7 420.9 428.6 438.1 440.1 19578.7 20782.8 21746.2 21973.5 22040.0 21761.1 22312.2 22578.5 22878.7 23895.0 6773.4 6878.3 7124.1 7163.6 7166.5 6928.9 6996.5 7053.4 7053.5 7055.5 7040.3 7059.2 7479.0 7789.8 7962.6 7623.7 7814.0 7884.4 7887.8 7951.6 7658.5 7660.5 7666.0 7679.5 7679.8 23411.9 25649.7 26123.6 26621.0 27485.6 2950.5 2970.1 2970.7 2982.3 2993.7 17743.1 17828.3 18290.1 18495.1 19237.2 1958.2 1959.7 1961.7 2024.6 2029.5 134412.0 148072.0 160382.2 161017.0 165823.0 23831.0 24859.5 24885.5 25080.4 25143.5 41713.0 41828.8 42940.8 42971.8 43038.8 8788.3 9734.2 10583.7 10765.0 10932.3 8201.3 8960.3 9000.0 9017.7 9182.6 8856.8 9638.3 9781.3 9803.8 9818.6 4057.7 4110.5 4134.2 4165.5 4302.6 1073.6 1079.0 1099.1 1102.1 1149.9 22574.9 22584.8 22916.0 23024.3 23158.4 1081.9 1100.7 1105.6 1126.8 1127.6 11081.2 11566.5 11793.9 11840.0 11853.2 746788.0 758464.0 762227.0 763036.0 764563.0 158950.0 160458.0 161149.5 161455.0 167795.0 1212.0 1240.2 1263.0 1271.6 1273.2 19194.4 19497.7 19888.0 19901.9 19957.6 60051.0 61527.0 61609.0 62294.7 62971.7 84564.6 84869.6 86809.0 88663.0 90278.0 11227.7 11373.4 11685.4 11733.0 11803.4 12462.8 12543.9 12632.2 12857.7 12875.5 10653.8 10800.9 10987.5 11135.8 11256.4 10205.8 10271.3 10378.3 10558.5 10606.5 11184.8 11215.0 11281.5 11427.7 11495.3 11443.9 11451.6 11788.3 11798.4 11804.6 10976.7 11006.5 11125.5 11131.5 11246.9 11:48:06 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:6869.82 Median:2486.95 stddev:4290.51 (172.5%) stddev-sans-first:4282.82 11:48:06 INFO - PROCESS | 1650 | Values: 1394.4 1463.7 1529.5 1579.4 1622.3 9350.6 9755.0 11011.4 11288.4 11293.7 9488.4 11131.9 11247.4 11253.8 11267.6 2429.3 2480.2 2485.6 2486.9 2487.0 2087.6 2156.7 2160.0 2354.6 2391.5 2454.3 2477.7 2488.4 2489.0 2492.7 2422.5 2440.8 2449.2 2450.6 2450.8 2419.0 2434.1 2437.8 2442.0 2443.6 9331.8 11102.8 11191.4 11281.8 11284.0 5922.3 6099.4 6103.2 6105.8 6117.8 3635.2 3708.1 3768.3 3789.3 3844.0 1283.6 1598.8 1922.7 1948.2 2034.5 15921.0 15991.8 16011.1 16036.2 16045.3 4721.7 4851.0 4874.3 4916.2 4941.6 11989.0 11992.5 12012.5 12022.0 12023.3 6025.8 6034.8 6039.2 6040.3 6043.2 5209.2 5411.5 5418.2 5480.9 5509.7 3877.7 3967.4 4013.2 4046.4 4060.8 848.4 978.8 1057.3 1057.8 1059.1 960.1 976.3 979.8 988.7 1019.8 6421.3 6602.4 6632.3 6714.8 6819.4 1018.8 1027.0 1043.1 1044.4 1062.5 4249.7 4317.9 4355.9 4448.2 4498.8 13709.9 13983.6 14020.9 14409.3 14427.9 13887.6 14148.2 14333.1 14359.6 14368.5 10298.2 10375.7 10426.2 10517.3 10524.7 6562.8 6639.5 6706.2 6745.6 6770.8 12349.8 12387.1 12423.6 12635.6 12676.8 12724.7 12786.2 12789.5 12937.2 12960.3 8474.6 8525.9 8628.8 8676.3 8693.2 10244.4 10337.9 10439.8 10443.3 10548.4 8845.7 8903.2 8946.4 8972.0 9027.2 8630.3 8744.2 8768.9 8967.8 8992.4 7895.2 7967.5 7988.8 8087.6 8116.9 5737.8 5884.4 6005.8 6049.4 6196.8 5849.2 6001.6 6074.0 6118.1 6125.8 11:48:06 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4563.28 Median:4889.45 stddev:1241.22 (25.4%) stddev-sans-first:1203.84 11:48:06 INFO - PROCESS | 1650 | Values: 355.0 373.8 387.8 391.5 396.6 5244.4 5272.3 5286.9 5311.5 5326.8 4305.5 5027.8 5069.3 5077.4 5153.9 3710.0 4248.5 4278.9 4282.5 4306.9 3854.4 4324.7 4329.9 4346.7 4359.2 3964.9 4179.4 4214.1 4268.8 4313.8 4182.9 4248.9 4261.1 4262.5 4270.2 3967.2 3983.4 3983.8 4003.5 4004.7 4196.0 4496.6 4699.7 4984.9 5086.7 3924.1 4421.8 4454.5 4843.6 4895.2 4951.8 5273.6 5326.9 5389.0 5427.5 3626.7 3901.4 4042.6 4236.1 4408.8 5007.0 5563.5 5597.9 5699.2 5812.4 3785.8 3918.6 4182.6 4402.7 4501.4 4157.5 4624.9 4652.4 4654.6 4688.5 4382.7 4656.9 4727.8 4738.6 4923.7 4707.3 5065.9 5378.4 5456.7 5491.5 1589.5 1625.0 1660.0 1662.5 1712.5 1230.9 1242.9 1254.6 1407.8 1428.3 4778.5 4842.1 5102.5 5122.3 5144.3 5143.7 5698.6 5699.9 5741.4 5750.1 4521.3 4560.0 4596.5 4699.6 4868.3 2542.7 2914.3 2941.4 2942.6 2957.5 4876.0 5260.7 5849.8 5874.7 5881.0 4976.9 5106.2 5322.7 5398.7 5726.8 5598.6 5649.4 5818.8 5820.2 6073.2 4673.0 5005.3 5583.3 5683.5 5732.6 4735.3 5381.9 5535.3 5545.3 5559.6 4932.9 5480.4 5546.6 5691.3 5805.7 4228.8 4688.4 4836.0 4850.8 4883.7 4911.8 5008.7 5536.2 5580.0 5633.9 4400.9 4990.4 5408.5 5467.3 5483.4 4927.8 5478.9 5516.5 5549.5 5615.6 4201.8 4663.9 4898.7 5035.7 5069.6 5512.4 5549.3 5550.6 5579.2 5631.5 4988.7 5315.5 5458.2 5459.6 5474.9 11:48:06 INFO - PROCESS | 1650 | 11:48:06 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:20966.31 Median:19615.36 stddev:30188.09 (153.9%) stddev-sans-first:30234.68 11:48:06 INFO - PROCESS | 1650 | Values: 768.5 787.5 827.0 884.9 887.7 16359.8 17345.2 17396.0 17400.5 17402.8 14039.0 14046.1 14119.6 14211.0 14288.6 960.1 978.8 980.0 981.3 982.8 812.8 974.2 976.0 982.9 1000.2 1043.7 1048.5 1048.5 1048.9 1049.7 1042.6 1044.6 1044.8 1045.7 1048.6 769.7 772.8 773.1 773.1 774.4 16734.2 17039.4 17067.0 17640.4 17923.8 14540.2 14583.7 14662.6 14710.9 14789.3 11403.4 11700.7 11790.4 11891.6 11991.5 3789.3 3815.9 3848.4 3896.3 3990.8 111583.9 112536.4 113108.0 113481.9 114829.0 10713.7 10779.4 11009.6 11340.0 11364.4 21306.9 21686.9 21714.5 21894.3 22025.7 6384.6 7874.8 7903.6 7907.4 7920.1 3940.2 4328.5 4382.8 4529.2 4680.9 3485.4 3587.2 3590.2 3592.9 3593.2 620.6 632.2 690.3 746.7 747.3 688.4 690.8 711.8 714.1 743.0 8088.6 10158.6 10223.5 10467.6 10711.4 694.4 704.7 707.0 707.6 719.6 4881.9 5382.4 5545.3 5663.3 5731.2 108197.0 109304.8 110565.4 111172.0 114188.0 58929.1 80623.6 83610.0 83636.6 86726.1 80568.0 82442.0 82858.6 82924.0 84477.6 9911.6 10032.2 10886.1 11273.4 11416.9 44929.8 45595.1 51581.2 51752.7 52466.2 64826.0 65851.0 66928.0 67870.7 69593.3 11704.8 13244.3 13437.9 13745.0 13979.4 15112.5 15310.2 15465.3 15786.8 15981.8 14119.9 14266.5 14416.5 14542.1 14609.4 14045.6 14142.7 14233.0 14467.4 14668.0 13223.5 13390.7 13883.2 13937.3 14185.4 9614.5 9739.0 9801.6 10285.4 10341.6 10943.7 11036.5 11333.0 11402.0 11420.8 11:48:06 INFO - PROCESS | 1650 | -------- Summary: end -------- 11:48:06 INFO - PROCESS | 1650 | 11:48:07 INFO - TEST-INFO | 1650: exit 0 11:48:07 INFO - TEST-OK | dromaeo_css | took 1118472ms 11:48:07 INFO - TEST-START | kraken 11:48:07 INFO - operating with platform_type : mac_ 11:48:07 INFO - Initialising browser for kraken test... 11:48:07 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmp8gjsxh/profile http://localhost:49219/getInfo.html) 11:48:09 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 11:48:09 INFO - PROCESS | 1661 | colorDepth:24 11:48:09 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 11:48:09 INFO - PROCESS | 1661 | __metrics 11:48:09 INFO - PROCESS | 1661 | 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" 11:48:09 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:48:10 INFO - TEST-INFO | 1661: exit 0 11:48:10 INFO - Browser initialized. 11:48:10 INFO - Running cycle 1/1 for kraken test... 11:48:10 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmp8gjsxh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:48:22 INFO - PROCESS | 1663 | RSS: Main: 169295872 11:48:22 INFO - PROCESS | 1663 | 11:48:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 11:48:26 INFO - PROCESS | 1663 | RSS: Main: 220139520 11:48:26 INFO - PROCESS | 1663 | 11:48:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 11:48:29 INFO - PROCESS | 1663 | RSS: Main: 230825984 11:48:29 INFO - PROCESS | 1663 | 11:48:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 11:48:33 INFO - PROCESS | 1663 | RSS: Main: 326717440 11:48:33 INFO - PROCESS | 1663 | 11:48:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 11:48:35 INFO - PROCESS | 1663 | RSS: Main: 228978688 11:48:35 INFO - PROCESS | 1663 | 11:48:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:48:38 INFO - PROCESS | 1663 | RSS: Main: 230707200 11:48:38 INFO - PROCESS | 1663 | 11:48:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 11:48:42 INFO - PROCESS | 1663 | RSS: Main: 271147008 11:48:42 INFO - PROCESS | 1663 | 11:48:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 11:48:45 INFO - PROCESS | 1663 | RSS: Main: 272777216 11:48:45 INFO - PROCESS | 1663 | 11:48:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 11:48:49 INFO - PROCESS | 1663 | RSS: Main: 268488704 11:48:49 INFO - PROCESS | 1663 | 11:48:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:48:51 INFO - PROCESS | 1663 | RSS: Main: 249217024 11:48:51 INFO - PROCESS | 1663 | 11:48:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:48:53 INFO - PROCESS | 1663 | RSS: Main: 251047936 11:48:53 INFO - PROCESS | 1663 | 11:48:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:48:56 INFO - PROCESS | 1663 | RSS: Main: 254382080 11:48:56 INFO - PROCESS | 1663 | 11:48:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:49:00 INFO - PROCESS | 1663 | RSS: Main: 271958016 11:49:00 INFO - PROCESS | 1663 | 11:49:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:49:03 INFO - PROCESS | 1663 | RSS: Main: 266137600 11:49:03 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 11:49:05 INFO - PROCESS | 1663 | RSS: Main: 310611968 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | __start_tp_report 11:49:05 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 11:49:05 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 11:49:05 INFO - PROCESS | 1663 | |i|pagename|runs| 11:49:05 INFO - PROCESS | 1663 | |0;ai-astar;116;121;169;161;114;114;114;114;114;114 11:49:05 INFO - PROCESS | 1663 | |1;audio-beat-detection;143;106;108;139;108;108;108;109;126;108 11:49:05 INFO - PROCESS | 1663 | |2;audio-dft;185;184;185;183;186;183;183;185;187;186 11:49:05 INFO - PROCESS | 1663 | |3;audio-fft;82;58;61;92;61;59;57;59;75;75 11:49:05 INFO - PROCESS | 1663 | |4;audio-oscillator;86;69;87;69;70;68;82;69;69;83 11:49:05 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;88;88;88;91;132;87;102;133;96;88 11:49:05 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;94;95;99;94;93;95;94;93;94 11:49:05 INFO - PROCESS | 1663 | |7;imaging-desaturate;85;87;83;85;82;86;86;86;86;84 11:49:05 INFO - PROCESS | 1663 | |8;json-parse-financial;61;59;59;60;63;62;64;64;61;62 11:49:05 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;54;61;60;62;60;58;57;58;58;59 11:49:05 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;80;84;80;81;84;81;80;86;81;81 11:49:05 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;133;148;139;135;130;137;138;139;175;144 11:49:05 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;161;161;163;161;161;163;158;157;159;163 11:49:05 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;65;65;64;63;65;62;64;63;61;63 11:49:05 INFO - PROCESS | 1663 | __end_tp_report 11:49:05 INFO - PROCESS | 1663 | __start_cc_report 11:49:05 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2094 11:49:05 INFO - PROCESS | 1663 | __end_cc_report 11:49:05 INFO - PROCESS | 1663 | __startTimestamp1494874145453__endTimestamp 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | ------- Summary: start ------- 11:49:05 INFO - PROCESS | 1663 | Number of tests: 14 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:125.10 Median:115.00 stddev:21.23 (18.5%) stddev-sans-first:22.26 11:49:05 INFO - PROCESS | 1663 | Values: 116.0 121.0 169.0 161.0 114.0 114.0 114.0 114.0 114.0 114.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:116.30 Median:108.50 stddev:14.24 (13.1%) stddev-sans-first:11.37 11:49:05 INFO - PROCESS | 1663 | Values: 143.0 106.0 108.0 139.0 108.0 108.0 108.0 109.0 126.0 108.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.42 (0.8%) stddev-sans-first:1.50 11:49:05 INFO - PROCESS | 1663 | Values: 185.0 184.0 185.0 183.0 186.0 183.0 183.0 185.0 187.0 186.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:67.90 Median:68.00 stddev:12.25 (18.0%) stddev-sans-first:11.88 11:49:05 INFO - PROCESS | 1663 | Values: 82.0 58.0 61.0 92.0 61.0 59.0 57.0 59.0 75.0 75.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:76.00 stddev:8.13 (10.7%) stddev-sans-first:7.63 11:49:05 INFO - PROCESS | 1663 | Values: 86.0 69.0 87.0 69.0 70.0 68.0 82.0 69.0 69.0 83.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:99.30 Median:88.00 stddev:18.12 (20.6%) stddev-sans-first:18.75 11:49:05 INFO - PROCESS | 1663 | Values: 88.0 88.0 88.0 91.0 132.0 87.0 102.0 133.0 96.0 88.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.50 stddev:1.71 (1.8%) stddev-sans-first:1.81 11:49:05 INFO - PROCESS | 1663 | Values: 95.0 94.0 95.0 99.0 94.0 93.0 95.0 94.0 93.0 94.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:86.00 stddev:1.56 (1.8%) stddev-sans-first:1.66 11:49:05 INFO - PROCESS | 1663 | Values: 85.0 87.0 83.0 85.0 82.0 86.0 86.0 86.0 86.0 84.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:62.00 stddev:1.84 (3.0%) stddev-sans-first:1.94 11:49:05 INFO - PROCESS | 1663 | Values: 61.0 59.0 59.0 60.0 63.0 62.0 64.0 64.0 61.0 62.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.50 stddev:2.26 (3.8%) stddev-sans-first:1.64 11:49:05 INFO - PROCESS | 1663 | Values: 54.0 61.0 60.0 62.0 60.0 58.0 57.0 58.0 58.0 59.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:81.00 stddev:2.10 (2.6%) stddev-sans-first:2.12 11:49:05 INFO - PROCESS | 1663 | Values: 80.0 84.0 80.0 81.0 84.0 81.0 80.0 86.0 81.0 81.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:139.00 stddev:12.74 (9.2%) stddev-sans-first:13.11 11:49:05 INFO - PROCESS | 1663 | Values: 133.0 148.0 139.0 135.0 130.0 137.0 138.0 139.0 175.0 144.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:161.00 stddev:2.11 (1.3%) stddev-sans-first:2.24 11:49:05 INFO - PROCESS | 1663 | Values: 161.0 161.0 163.0 161.0 161.0 163.0 158.0 157.0 159.0 163.0 11:49:05 INFO - PROCESS | 1663 | 11:49:05 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:64.00 stddev:1.35 (2.1%) stddev-sans-first:1.32 11:49:05 INFO - PROCESS | 1663 | Values: 65.0 65.0 64.0 63.0 65.0 62.0 64.0 63.0 61.0 63.0 11:49:05 INFO - PROCESS | 1663 | -------- Summary: end -------- 11:49:05 INFO - PROCESS | 1663 | 11:49:06 INFO - TEST-INFO | 1663: exit 0 11:49:06 INFO - TEST-OK | kraken | took 59150ms 11:49:06 INFO - SUITE-END | took 1177s 11:49:06 INFO - Completed test suite (00:19:37) 11:49:06 INFO - javascript benchmark 11:49:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7927.858413765102, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3507.824608769561, 3523.5067090607313, 3523.665251801078, 3540.964590354096, 3586.479960405741, 6207.410296021876, 6215.4095360940755, 6218.0362043883215, 6250.062490626408, 6268.561200715952, 5609.943900560993, 5844.94790937631, 6198.59709118907, 6271.749130034793, 6284.308726040132, 5460.126379779226, 5514.972425137868, 5547.972260138694, 5556.722163891809, 5644.6613203207735, 5444.101723215685, 5444.673319600817, 5459.617826752129, 5587.469190426903, 5618.129189975561, 5541.0, 5547.44525547446, 5554.444555544454, 5565.582581306398, 5579.191017302489, 5606.103023516259, 5611.382747897728, 5614.943850561483, 5625.662460252357, 5626.85932851682, 5381.973090134564, 5491.395946445888, 5541.473560011833, 5550.0, 5577.247071645316, 6112.929053818819, 6131.847187782227, 6140.785072522441, 6162.445379915829, 6163.291221509539, 5354.464553544653, 6309.116723658645, 6315.24217093947, 6327.272363678144, 6356.110144579762, 6509.967450162766, 6532.11816404784, 6534.640594767286, 6561.114249576294, 6582.1114149589675, 6276.717547710318, 6311.558190926637, 6315.052742088724, 6320.5891617044745, 6324.272708638566, 6500.804975850732, 6796.864062718817, 6817.829554261183, 6818.522703410813, 6823.112995310677, 6311.715972781236, 6319.368063193644, 6336.334684858116, 6373.362663733589, 6416.390442907917, 6567.9671601641685, 6588.374104460068, 6603.636799975951, 6611.966940165365, 6623.536352455282, 6231.750729970841, 6368.267649220306, 6443.742250309935, 6451.903221451682, 6572.34276572339, 6308.4007019334, 6311.779087731908, 6319.178506794087, 6323.20959880015, 6323.715432805535, 4912.680675756064, 5288.709120998382, 5339.599530035185, 5340.519353258148, 5346.7861285548925, 4689.3903792507435, 4692.131955588227, 4695.600873925686, 4701.0, 4750.311204875274, 5283.233931079972, 6168.228971378578, 6170.0, 6248.0, 6269.216347956505, 6188.659623720649, 6396.584222025554, 6460.870782584322, 6501.902471462932, 6551.901721474182, 6237.937620623736, 6250.312465628777, 6254.249490061131, 6260.530460215502, 6263.31103578606, 5688.8862222755315, 5791.218185544982, 5819.185314056035, 5839.124131380242, 5843.0066888629035, 6417.550771445954, 6469.159009328757, 6669.433098186709, 6718.496112791658, 6765.932340676531, 6532.902006470002, 6567.868642627216, 6582.76960306387, 6585.769498067544, 6592.901106483502, 6642.867142657216, 6770.830729231809, 6773.559718618274, 6776.322367763184, 6790.388865002174, 6266.185395898504, 6325.1144839721555, 6371.585846919936, 6482.643454610042, 6486.708098135689, 6555.934440655532, 6567.27759946396, 6577.769778057745, 6600.273969863407, 6614.173228346457, 6371.74513019474, 6374.649394283359, 6374.93625063762, 6375.394337537927, 6382.936170638234, 5344.064788661891, 6254.3745625437095, 6312.43188113072, 6335.176427064452, 6386.96806515983, 5448.182772584144, 6224.439800417984, 6324.494040476843, 6324.525660575383, 6348.746050157942, 6340.365963403623, 6344.809655710336, 6352.237731472253, 6356.300806911144, 6387.5209359297305, 5451.809186678447, 6112.26032301701, 6140.631562106288, 6154.446099851035, 6175.6912154393], "value": 6003.639685626567, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.7426849139781, 800.8718605023196, 821.5563595658343, 904.0236544531906, 911.1162172692486, 29243.122706318816, 41578.168436631284, 41959.58040419595, 42048.57951420485, 42121.57878421215, 42591.0, 42832.357514637304, 42850.785746071226, 42887.35668964968, 42975.0, 7940.1265860755275, 9560.617575296998, 9719.17387022104, 9738.415695058284, 9766.36518626287, 7980.920190798076, 11595.94202028993, 11834.940825295862, 12013.879861201407, 12018.51925922962, 11762.23545469546, 11863.881361186364, 11864.644060678194, 11867.228630139056, 11872.347020913847, 11762.235454695418, 11792.233504822245, 11826.172167948247, 11837.644870653894, 11846.348450835201, 11561.0, 11609.4195290236, 11644.534218631245, 11657.883421165849, 11661.70845728855, 40553.0, 40667.389989150186, 40706.796466017484, 40779.38830917539, 40795.14433989622, 3987.8803635890968, 3997.760134391946, 4005.1589166274825, 4006.5192176938954, 4056.6349028587274, 27520.862395687895, 28235.15294541167, 30182.09453716392, 30646.38707225843, 30652.0, 3179.9046028619177, 3228.1122691259857, 3299.15871452778, 3320.9833950830093, 3438.5529881115544, 265673.3432665687, 337470.0, 341931.0, 342069.0, 343380.0, 52773.0, 52827.20759188615, 52838.679033024484, 52852.73573632184, 53081.93836123256, 55471.0, 55616.0, 55619.887602248535, 55627.0, 55640.887182256934, 15136.243187840564, 15563.988340757815, 15586.688266234838, 15595.376184952702, 15654.37382504687, 11867.643970680721, 13319.467221311234, 13333.66665833362, 13338.533151339656, 13368.933155334162, 10813.459327033617, 10859.402732849769, 12088.576899808464, 12313.69215769595, 12399.318037508023, 5685.630434021776, 5712.428757124338, 5734.455226753452, 5740.913886291709, 5759.8560035999435, 1150.3270586706703, 1173.4660729368118, 1181.1141643767176, 1186.2822992089834, 1207.8836601567934, 25833.870830645727, 26710.86644566765, 26963.865180673973, 26988.59517107245, 27503.44993100167, 953.7758626722854, 1147.6270212181075, 1157.5311998640564, 1166.2011522107384, 1315.6973896003972, 15400.0, 15433.0, 15470.143582769915, 15551.844481555267, 15810.60473488172, 457521.0, 457824.0, 459880.0, 460300.0, 460915.0, 60141.0, 60325.0, 60353.79292414127, 60568.69715651393, 60731.39268607346, 1459.6204986703322, 1476.8596983286786, 1499.642823244965, 1502.6543894904014, 1506.9698606027819, 27505.724942751116, 28016.439671206055, 28774.568381474714, 29369.0, 29394.0, 48552.02895942132, 48642.0, 48691.0, 48740.512594875014, 48834.26748598845, 437994.62005380815, 442111.0, 442191.0, 442297.0, 442403.0, 32340.514892277104, 33094.00717978465, 33129.83435082809, 33239.667603324626, 33262.66737332693, 31689.683103168674, 35935.281294373446, 39785.60214397819, 48188.79528011828, 49192.508074920224, 31068.689313107483, 31364.0, 31842.73470201334, 31886.6811331893, 32667.836660817495, 26569.867150664122, 30951.226219344695, 30970.84514577413, 31272.843635781675, 32277.838610806797, 37327.6267237335, 38413.23173536458, 38715.612843871204, 38721.806390968995, 38729.6127038726, 30320.84839575788, 30796.07611771561, 31263.68736312699, 32507.512387313735, 32998.67001329956, 23912.521749565258, 25207.49585008326, 25286.7362026932, 25669.614955775305, 25690.100846470283], "value": 19512.228978135314, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.73450048104957, 420.91160856220193, 428.56714718134674, 438.08160343593414, 440.1351344607845, 19578.70631940522, 20782.792172078276, 21746.238881639147, 21973.450663733416, 22040.0, 21761.129554817788, 22312.21907233247, 22578.54842903141, 22878.65682014771, 23895.0, 6773.4242589379965, 6878.3465570770695, 7124.073870396841, 7163.570185788869, 7166.462515311347, 6928.9307106929045, 6996.545224560387, 7053.400460960799, 7053.506254562183, 7055.470839687018, 7040.331168538981, 7059.2234854166, 7479.0, 7789.76630701077, 7962.5620590867475, 7623.656935437919, 7813.960930195312, 7884.448088633798, 7887.763367098996, 7951.642176102089, 7658.502197357154, 7660.463767536275, 7666.0, 7679.462437629368, 7679.769606911802, 23411.94646240906, 25649.74350256492, 26123.563204023772, 26621.0, 27485.587716184273, 2950.498415269409, 2970.1386597886585, 2970.732634062923, 2982.2991596974693, 2993.745531629814, 17743.11284435773, 17828.286868525243, 18290.08549572516, 18495.075246237768, 19237.23051077955, 1958.1971391729464, 1959.6864501679806, 1961.7038685279335, 2024.5545979884403, 2029.4723371923258, 134412.0, 148072.0, 160382.19808900997, 161017.0, 165823.0, 23831.0, 24859.5028099437, 24885.5022899541, 25080.37299067538, 25143.497130057298, 41713.0, 41828.790856045525, 42940.78529607332, 42971.78514107472, 43038.78480607577, 8788.340874434443, 9734.172595329335, 10583.682489525327, 10765.0, 10932.344059356465, 8201.343892488587, 8960.283177345797, 8999.955000225087, 9017.684381046633, 9182.586783594754, 8856.822863542822, 9638.277129215337, 9781.266405019653, 9803.754906127262, 9818.607255709692, 4057.715959882839, 4110.479043401069, 4134.214499245134, 4165.520965088993, 4302.5586428546785, 1073.613499140309, 1078.9892101079047, 1099.1261946752352, 1102.1293178388983, 1149.9137564682683, 22574.887125564597, 22584.774152258597, 22915.968781404878, 23024.309270721904, 23158.42103947415, 1081.9296745711504, 1100.6753007862699, 1105.5688281570194, 1126.8478755367921, 1127.577158565538, 11081.168912331608, 11566.537338506534, 11793.88206117945, 11840.0, 11853.170278080452, 746788.0, 758464.0, 762227.0, 763036.0, 764563.0, 158950.0, 160458.0, 161149.52528085638, 161455.0, 167795.0, 1211.9697007574705, 1240.2496489623647, 1262.9936850315873, 1271.5867343113525, 1273.2233337664202, 19194.424167275003, 19497.70753438728, 19888.0, 19901.900490497454, 19957.60084798325, 60051.0, 61527.0, 61609.0, 62294.688526557075, 62971.685141574, 84564.57717711372, 84869.5756521238, 86809.0, 88663.0, 90278.0, 11227.663170104583, 11373.43132843338, 11685.422467967055, 11733.00269477104, 11803.350815705218, 12462.750744985231, 12543.874561254635, 12632.178908371294, 12857.678558036125, 12875.484980601046, 10653.786924261627, 10800.945995269973, 10987.450627468435, 11135.777284454103, 11256.437178140897, 10205.795884082128, 10271.332363396356, 10378.27352085377, 10558.472076395996, 10606.522706478072, 11184.776304474028, 11215.0, 11281.548738050386, 11427.657170284905, 11495.31028138281, 11443.942780286045, 11451.59919402817, 11788.29270243788, 11798.410079496163, 11804.586839460577, 10976.670699879016, 11006.504707288032, 11125.499352528994, 11131.499082541142, 11246.887531124585], "value": 12103.261574491624, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.4491925689347, 1463.7218928403606, 1529.4952665620347, 1579.399828065335, 1622.2699785096709, 9350.579223934923, 9755.0, 11011.449427528632, 11288.379139147344, 11293.661190164288, 9488.430694158355, 11131.888681113187, 11247.437628118581, 11253.774924501526, 11267.60563380282, 2429.2590759818204, 2480.231128350208, 2485.5898776701824, 2486.9005239790386, 2487.0, 2087.561612061469, 2156.6872803443493, 2160.0, 2354.5526349993447, 2391.5097405031925, 2454.2759885833634, 2477.6531285620026, 2488.415222422729, 2489.0043982407, 2492.675952126221, 2422.4549476367943, 2440.7803297703203, 2449.167283123729, 2450.5711500487523, 2450.791682706972, 2418.9879050604636, 2434.0628857889665, 2437.756224377566, 2442.0, 2443.6334549817493, 9331.76670583234, 11102.833457498064, 11191.44042797865, 11281.774364512747, 11283.984441400313, 5922.3485416604135, 6099.420555047263, 6103.206583144163, 6105.786297479567, 6117.755289788403, 3635.2366003139373, 3708.054446116223, 3768.321702093622, 3789.3179227738997, 3843.961560384388, 1283.57641978147, 1598.8161361443467, 1922.7308176855252, 1948.1915005272795, 2034.5218873564847, 15921.0, 15991.840081599152, 16011.119388433632, 16036.198190090565, 16045.277962491598, 4721.740304283228, 4850.95149048505, 4874.293227481995, 4916.188828843201, 4941.604671626261, 11989.040876729841, 11992.520299188109, 12012.459439325252, 12022.0, 12023.338716370683, 6025.789097381658, 6034.758609655652, 6039.214902062704, 6040.275166979991, 6043.184170136975, 5209.218617207449, 5411.512963833274, 5418.160185171305, 5480.91778623321, 5509.696966666792, 3877.728559000899, 3967.3652215645357, 4013.177298653769, 4046.4132700758814, 4060.7563546187325, 848.3722045686147, 978.7748817771952, 1057.3233130796295, 1057.7778666479971, 1059.0786016165985, 960.1023043454253, 976.3360914578013, 979.7501637082498, 988.7083310423442, 1019.7552587378975, 6421.29365769765, 6602.415420299094, 6632.2704502504685, 6714.832129196711, 6819.386255237053, 1018.8064267789097, 1026.9640562580273, 1043.113353649392, 1044.4412239451856, 1062.4953147254992, 4249.723767955073, 4317.870463886088, 4355.937783719618, 4448.177087238806, 4498.775061246924, 13709.862901371058, 13983.580492585239, 14020.85979140216, 14409.279536023158, 14427.92786036063, 13887.58337249884, 14148.221847798264, 14333.06835055718, 14359.641008974859, 14368.497102601359, 10298.17614590846, 10375.74060648505, 10426.218033647507, 10517.316374435637, 10524.736881577868, 6562.77030303937, 6639.468842492685, 6706.228783689743, 6745.5952642841585, 6770.763023294161, 12349.75300494003, 12387.132900696864, 12423.62729118128, 12635.557755478514, 12676.809847852473, 12724.68188295263, 12786.168899021533, 12789.488420463449, 12937.223766574036, 12960.287184204959, 8474.576271186539, 8525.87211191845, 8628.784280392789, 8676.305895528216, 8693.217610415093, 10244.385336879513, 10337.896621033693, 10439.843402348819, 10443.321184123008, 10548.419836909045, 8845.69040083594, 8903.19871211594, 8946.37375383717, 8972.0, 9027.187553120251, 8630.266427353745, 8744.169303916118, 8768.868466973128, 8967.775805604912, 8992.415492992934, 7895.171007044178, 7967.521948683097, 7988.800279993046, 8087.63605637736, 8116.918830811853, 5737.770489180386, 5884.440978107073, 6005.819825405245, 6049.364816694359, 6196.81409557714, 5849.239598852292, 6001.5798894077, 6074.0, 6118.112873633388, 6125.785597504244], "value": 5277.683145947032, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.9751517393782, 373.7925451374486, 387.7576514678326, 391.48519696598987, 396.5558574396676, 5244.423113457517, 5272.261883336336, 5286.863324385256, 5311.468853114687, 5326.813561525338, 4305.526392096866, 5027.773750181242, 5069.3156423882765, 5077.41609714882, 5153.948460515394, 3709.981450092746, 4248.511421186572, 4278.871633850989, 4282.507511636155, 4306.913861722764, 3854.4025676020124, 4324.740515569076, 4329.913401731964, 4346.652267818583, 4359.17175736609, 3964.8612298569556, 4179.414881916534, 4214.093969796493, 4268.807903644328, 4313.805878735465, 4182.91634167318, 4248.916526285778, 4261.126469073833, 4262.488501379854, 4270.210011147932, 3967.2462232175794, 3983.4224037514687, 3983.7609743415487, 4003.5195776506716, 4004.739691920016, 4196.013936724868, 4496.55034496551, 4699.7415142167165, 4984.900301993976, 5086.694798312114, 3924.13668992824, 4421.784009397409, 4454.532274111237, 4843.564079232886, 4895.216765317532, 4951.752412379403, 5273.604479664041, 5326.946730532683, 5388.973055134699, 5427.530473634526, 3626.709863210937, 3901.395283731026, 4042.5957404259634, 4236.131593023453, 4408.801603927831, 5006.997201119581, 5563.499285064364, 5597.888042239132, 5699.202111704365, 5812.447817457335, 3785.7917814519915, 3918.5885482024546, 4182.644475219579, 4402.669799765031, 4501.369808226851, 4157.503298812425, 4624.884377890579, 4652.395188625457, 4654.604358629486, 4688.530487439339, 4382.748733728278, 4656.906861862743, 4727.84167878865, 4738.573528382462, 4923.735081862384, 4707.340972263886, 5065.860181459216, 5378.435264297271, 5456.672599644034, 5491.517290331668, 1589.4595837415334, 1624.951251462458, 1660.01229268585, 1662.4597005972864, 1712.4862541237578, 1230.913836031487, 1242.9012163728914, 1254.5671743248565, 1407.7677183264852, 1428.250168661461, 4778.474367819537, 4842.143135550043, 5102.469259222218, 5122.308488354099, 5144.279800827887, 5143.691378517226, 5698.601097923106, 5699.924553943867, 5741.368449470555, 5750.137479378127, 4521.276595744665, 4560.0463367463935, 4596.517365676623, 4699.624030077585, 4868.342773725572, 2542.720300766914, 2914.329704168053, 2941.352902361477, 2942.6321709786275, 2957.452871218831, 4876.003773038738, 5260.658532074297, 5849.771547974888, 5874.706264686749, 5880.970595147082, 4976.90046199074, 5106.212825511041, 5322.650736010285, 5398.676079435247, 5726.828195154152, 5598.552115830724, 5649.350324712628, 5818.796342128006, 5820.224831426371, 6073.240844894388, 4672.953270467252, 5005.324281221989, 5583.277140930579, 5683.516901063456, 5732.57005724564, 4735.313379559876, 5381.946180538144, 5535.335759708861, 5545.251391062155, 5559.582306511814, 4932.8520144395725, 5480.424555421623, 5546.58400619947, 5691.288588926384, 5805.738741756547, 4228.767417792051, 4688.437387513521, 4836.0, 4850.7817148227705, 4883.682560633548, 4911.754412279301, 5008.6744361616375, 5536.197251398606, 5579.995600791896, 5633.887322253614, 4400.867973960786, 4990.378127108632, 5408.486193811582, 5467.343918729778, 5483.367983368085, 4927.802887884444, 5478.863028424321, 5516.531094856982, 5549.500544950813, 5615.578831587565, 4201.810918508613, 4663.906721865612, 4898.681585696918, 5035.658418835259, 5069.5944324454695, 5512.39363670004, 5549.334079910436, 5550.58370622213, 5579.158877570473, 5631.549476041825, 4988.65306367286, 5315.521603055744, 5458.181272808952, 5459.6250630975655, 5474.91787623194], "value": 4214.131765963603, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.5427170833356, 787.5117427195137, 826.9545175015379, 884.8893888263967, 887.6871921549525, 16359.754603680954, 17345.219465124068, 17396.043217623024, 17400.477985660436, 17402.82597174028, 14039.0, 14046.08700434471, 14119.576412707609, 14211.0, 14288.642783930381, 960.0927123867913, 978.848278516831, 979.9853002204973, 981.2542467724545, 982.7837875667377, 812.8130529978108, 974.1914211204684, 976.0, 982.8722266105432, 1000.194843151263, 1043.7338478687961, 1048.5019615682565, 1048.5124417146026, 1048.9318194317345, 1049.6746008737277, 1042.6090216168936, 1044.639599338227, 1044.8067107585084, 1045.7124290820009, 1048.6434612231878, 769.7190525458168, 772.8029352515073, 773.0568706178453, 773.1186447449911, 774.3611520495614, 16734.24695888688, 17039.403620873334, 17067.0, 17640.38258660941, 17923.820761792475, 14540.200288984102, 14583.708325833424, 14662.560123196321, 14710.926445367812, 14789.334479948428, 11403.3728144952, 11700.707482312926, 11790.35153066581, 11891.643250702493, 11991.520339186422, 3789.2989796887387, 3815.904602384963, 3848.422736589506, 3896.2597106549665, 3990.7805070721097, 111583.88416115816, 112536.4373178137, 113108.0, 113481.86518134795, 114829.0, 10713.732156696145, 10779.353238805697, 11009.559617615367, 11340.0, 11364.431778411212, 21306.893465532885, 21686.915654217228, 21714.457138571663, 21894.343169704935, 22025.66961495579, 6384.648844313608, 7874.842503149905, 7903.604819759104, 7907.406944479187, 7920.089189743138, 3940.172563761585, 4328.502222244482, 4382.846600368972, 4529.184746745552, 4680.9297860532115, 3485.4423292273113, 3587.2466781975554, 3590.2281009583066, 3592.856285748593, 3593.2454184621524, 620.6183197333697, 632.2128949457863, 690.3062422265591, 746.6975874770726, 747.3274053352027, 688.435482904024, 690.7927621713466, 711.8398360368875, 714.1465948191861, 743.0266351080103, 8088.555129467818, 10158.593656253815, 10223.539940702687, 10467.633632822815, 10711.410872401937, 694.3646563394437, 704.7125161123405, 706.9575825450489, 707.5789905006616, 719.6150059718026, 4881.877953051202, 5382.407935127133, 5545.279113715191, 5663.292088488939, 5731.229229269129, 108197.0, 109304.81390372149, 110565.44717276363, 111172.0, 114188.0, 58929.116063258225, 80623.59688201522, 83610.0, 83636.58181709053, 86726.1327386718, 80568.0, 82442.0, 82858.58570707108, 82924.0, 84477.57761211155, 9911.603535858774, 10032.197424206191, 10886.074683651977, 11273.436328183394, 11416.94291528537, 44929.77535112303, 45595.08809823851, 51581.226281606556, 51752.74123629358, 52466.213006804166, 64826.0, 65851.0, 66927.9960800598, 67870.66064669644, 69593.30406695868, 11704.824427633423, 13244.337783110614, 13437.932810335886, 13745.0, 13979.440822367398, 15112.471063512725, 15310.234488275319, 15465.304061317043, 15786.842131578996, 15981.840181598036, 14119.858801411854, 14266.500672476828, 14416.495422660157, 14542.127472351693, 14609.415623375371, 14045.648858778612, 14142.717145657234, 14233.012019516626, 14467.421303147756, 14668.046576972463, 13223.471061157446, 13390.732185356434, 13883.236421996888, 13937.30313484342, 14185.432582696576, 9614.51927403613, 9739.026097390204, 9801.558929848317, 10285.382877027403, 10341.586336546454, 10943.726406839893, 11036.503357348778, 11333.022767144701, 11402.0, 11420.771584568429], "value": 7872.590674848823, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1416.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 121.0, 169.0, 161.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 106.0, 108.0, 139.0, 108.0, 108.0, 108.0, 109.0, 126.0, 108.0], "value": 116.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 185.0, 183.0, 186.0, 183.0, 183.0, 185.0, 187.0, 186.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 61.0, 92.0, 61.0, 59.0, 57.0, 59.0, 75.0, 75.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 87.0, 69.0, 70.0, 68.0, 82.0, 69.0, 69.0, 83.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 91.0, 132.0, 87.0, 102.0, 133.0, 96.0, 88.0], "value": 99.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 99.0, 94.0, 93.0, 95.0, 94.0, 93.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 83.0, 85.0, 82.0, 86.0, 86.0, 86.0, 86.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 59.0, 60.0, 63.0, 62.0, 64.0, 64.0, 61.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 60.0, 62.0, 60.0, 58.0, 57.0, 58.0, 58.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 80.0, 81.0, 84.0, 81.0, 80.0, 86.0, 81.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 148.0, 139.0, 135.0, 130.0, 137.0, 138.0, 139.0, 175.0, 144.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 161.0, 161.0, 163.0, 158.0, 157.0, 159.0, 163.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 63.0, 65.0, 62.0, 64.0, 63.0, 61.0, 63.0], "value": 63.5, "unit": "score"}]}]} 11:49:06 INFO - Return code: 0 11:49:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:49:06 INFO - # TBPL SUCCESS # 11:49:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:49:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:49:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:49:06 INFO - 2017-05-15 11:49:06.780 screenresolution[1667:10176] starting screenresolution argv=screenresolution get 11:49:06 INFO - 2017-05-15 11:49:06.783 screenresolution[1667:10176] Display 0: 1600x1200x32@60 11:49:06 INFO - 2017-05-15 11:49:06.789 screenresolution[1668:10179] starting screenresolution argv=screenresolution list 11:49:06 INFO - Available Modes on Display 0 11:49:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:49:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:49:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:49:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:49:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:49:07 INFO - Intel Iris: 11:49:07 INFO - Chipset Model: Intel Iris 11:49:07 INFO - Type: GPU 11:49:07 INFO - Bus: Built-In 11:49:07 INFO - VRAM (Dynamic, Max): 1536 MB 11:49:07 INFO - Vendor: Intel (0x8086) 11:49:07 INFO - Device ID: 0x0a2e 11:49:07 INFO - Revision ID: 0x0009 11:49:07 INFO - Displays: 11:49:07 INFO - Display: 11:49:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:49:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:49:07 INFO - Display Serial Number: 1600x1200 60 11:49:07 INFO - Main Display: Yes 11:49:07 INFO - Mirror: Off 11:49:07 INFO - Online: Yes 11:49:07 INFO - Rotation: Supported 11:49:07 INFO - Return code: 0 11:49:07 INFO - Running post-action listener: _resource_record_post_action 11:49:07 INFO - [mozharness: 2017-05-15 18:49:07.024377Z] Finished run-tests step (success) 11:49:07 INFO - Running post-run listener: _resource_record_post_run 11:49:07 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 39134208; Write bytes: 422985728; Read time: 250; Write time: 1407 11:49:07 INFO - TinderboxPrint: CPU usage
54.1% 11:49:07 INFO - TinderboxPrint: I/O read bytes / time
39,134,208 / 250 11:49:07 INFO - TinderboxPrint: I/O write bytes / time
422,985,728 / 1,407 11:49:07 INFO - TinderboxPrint: CPU idle
2,192.3 (45.9%) 11:49:07 INFO - TinderboxPrint: CPU system
958.9 (20.1%) 11:49:07 INFO - TinderboxPrint: CPU user
1,622.5 (34.0%) 11:49:07 INFO - TinderboxPrint: Swap in / out
160,251,904 / 0 11:49:07 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121733632; Write bytes: 113971200; Read time: 11185; Write time: 246 11:49:07 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35083264; Write bytes: 298868736; Read time: 224; Write time: 1127 11:49:07 INFO - Running post-run listener: _upload_blobber_files 11:49:07 INFO - Blob upload gear active. 11:49:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:49:07 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 11:49:07 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'] 11:49:07 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 11:49:07 INFO - (blobuploader) - INFO - Open directory for files ... 11:49:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:49:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:49:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:09 INFO - (blobuploader) - INFO - Done attempting. 11:49:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:49:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:49:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:10 INFO - (blobuploader) - INFO - Done attempting. 11:49:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:49:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:49:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:11 INFO - (blobuploader) - INFO - Done attempting. 11:49:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:49:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:49:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:13 INFO - (blobuploader) - INFO - Done attempting. 11:49:13 INFO - (blobuploader) - INFO - Iteration through files over. 11:49:13 INFO - Return code: 0 11:49:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:49:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:49:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/459a66dd83c3d5501428b590974a0f2460a77c62f83ed5db86fe6571b360baaf15c243cdbe25f20cfa0442c50e6ffb359e46ad19dd6d611315cef0e765f2f125", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ca58cf80ce418dcf3ed47be5987b64221ed99b863b6cbaa75f79f218bac56cc8cce09a45047aa036bab3e091342898b2a3ef4d20f9512ef35d2c2c224230fa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7cc3bf5959c916567df77148bfc7d18855210787377881e47171b0d217a56a6419f2ecfa10b89c0128c1b49c2d172f8d73c226c8d0031f4a39cb616d16725ffe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/af3a1ab4a9a09354de752cf7555e6cca76b2798060fc1fe1922fca7c6eb96e762e39f77e7089d4986939531879a6b10110ac74d74d431e60abf478e4b6f6c449"} 11:49:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:49:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:49:13 INFO - Contents: 11:49:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/459a66dd83c3d5501428b590974a0f2460a77c62f83ed5db86fe6571b360baaf15c243cdbe25f20cfa0442c50e6ffb359e46ad19dd6d611315cef0e765f2f125", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ca58cf80ce418dcf3ed47be5987b64221ed99b863b6cbaa75f79f218bac56cc8cce09a45047aa036bab3e091342898b2a3ef4d20f9512ef35d2c2c224230fa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7cc3bf5959c916567df77148bfc7d18855210787377881e47171b0d217a56a6419f2ecfa10b89c0128c1b49c2d172f8d73c226c8d0031f4a39cb616d16725ffe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/af3a1ab4a9a09354de752cf7555e6cca76b2798060fc1fe1922fca7c6eb96e762e39f77e7089d4986939531879a6b10110ac74d74d431e60abf478e4b6f6c449"} 11:49:13 INFO - Running post-run listener: copy_logs_to_upload_dir 11:49:13 INFO - Copying logs to upload dir... 11:49:13 INFO - mkdir: /builds/slave/test/build/upload/logs 11:49:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.441715 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 11:49:13.950958) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 11:49:13.954710) ========= 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.xASM1xf6B3/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.rcINlBPyID/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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/459a66dd83c3d5501428b590974a0f2460a77c62f83ed5db86fe6571b360baaf15c243cdbe25f20cfa0442c50e6ffb359e46ad19dd6d611315cef0e765f2f125", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ca58cf80ce418dcf3ed47be5987b64221ed99b863b6cbaa75f79f218bac56cc8cce09a45047aa036bab3e091342898b2a3ef4d20f9512ef35d2c2c224230fa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7cc3bf5959c916567df77148bfc7d18855210787377881e47171b0d217a56a6419f2ecfa10b89c0128c1b49c2d172f8d73c226c8d0031f4a39cb616d16725ffe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/af3a1ab4a9a09354de752cf7555e6cca76b2798060fc1fe1922fca7c6eb96e762e39f77e7089d4986939531879a6b10110ac74d74d431e60abf478e4b6f6c449"} build_url:https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/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/459a66dd83c3d5501428b590974a0f2460a77c62f83ed5db86fe6571b360baaf15c243cdbe25f20cfa0442c50e6ffb359e46ad19dd6d611315cef0e765f2f125", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ca58cf80ce418dcf3ed47be5987b64221ed99b863b6cbaa75f79f218bac56cc8cce09a45047aa036bab3e091342898b2a3ef4d20f9512ef35d2c2c224230fa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7cc3bf5959c916567df77148bfc7d18855210787377881e47171b0d217a56a6419f2ecfa10b89c0128c1b49c2d172f8d73c226c8d0031f4a39cb616d16725ffe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/af3a1ab4a9a09354de752cf7555e6cca76b2798060fc1fe1922fca7c6eb96e762e39f77e7089d4986939531879a6b10110ac74d74d431e60abf478e4b6f6c449"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 11:49:13.991168) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:49:13.991552) ========= 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.xASM1xf6B3/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.rcINlBPyID/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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.005078 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:49:14.041786) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 11:49:14.042247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 11:49:14.042634) ========= ========= Total master_lag: 3.17 =========