builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0351 starttime: 1496057181.94 results: success (0) buildid: 20170529025116 builduid: 79fe63c8b69a40189082b1314cc4623f revision: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:21.935533) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:21.936001) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:21.936319) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:21.962930) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-29 04:26:21.963579) ========= 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.K06SeZ4f48/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.n9uqKclqyZ/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-29 04:26:21-- 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% 7.77M=0.001s 2017-05-29 04:26:23 (7.77 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.381960 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-29 04:26:23.378766) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:23.379369) ========= 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.K06SeZ4f48/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.n9uqKclqyZ/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.021869 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:23.431169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-29 04:26:23.431980) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 715d88d0bd826c9e4a5403d6561a2fcc229dd208 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 715d88d0bd826c9e4a5403d6561a2fcc229dd208 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K06SeZ4f48/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.n9uqKclqyZ/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-29 04:26:23,441 truncating revision to first 12 chars 2017-05-29 04:26:23,441 Setting DEBUG logging. 2017-05-29 04:26:23,441 attempt 1/10 2017-05-29 04:26:23,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/715d88d0bd82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 04:26:24,915 unpacking tar archive at: mozilla-beta-715d88d0bd82/testing/mozharness/ program finished with exit code 0 elapsedTime=1.742330 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-29 04:26:25.202866) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:25.203685) ========= script_repo_revision: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:25.204766) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:25.205490) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 04:26:25.243455) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2017-05-29 04:26:25.243835) ========= /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.K06SeZ4f48/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.n9uqKclqyZ/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:26:25 INFO - MultiFileLogger online at 20170529 04:26:25 in /builds/slave/test 04:26:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:26:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:26:25 INFO - {'append_to_log': False, 04:26:25 INFO - 'base_work_dir': '/builds/slave/test', 04:26:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:26:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:26:25 INFO - 'branch': 'Mozilla-Beta', 04:26:25 INFO - 'buildbot_json_path': 'buildprops.json', 04:26:25 INFO - 'config_files': ('talos/mac_config.py',), 04:26:25 INFO - 'default_actions': ('clobber', 04:26:25 INFO - 'read-buildbot-config', 04:26:25 INFO - 'download-and-extract', 04:26:25 INFO - 'populate-webroot', 04:26:25 INFO - 'create-virtualenv', 04:26:25 INFO - 'install', 04:26:25 INFO - 'run-tests'), 04:26:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:26:25 INFO - 'download_minidump_stackwalk': True, 04:26:25 INFO - 'download_symbols': 'ondemand', 04:26:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:26:25 INFO - 'tooltool.py': '/tools/tooltool.py', 04:26:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:26:25 INFO - '/tools/misc-python/virtualenv.py')}, 04:26:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:26:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:26:25 INFO - 'gecko_profile': False, 04:26:25 INFO - 'gecko_profile_interval': 0, 04:26:25 INFO - 'installer_path': 'installer.exe', 04:26:25 INFO - 'log_level': 'info', 04:26:25 INFO - 'log_name': 'talos', 04:26:25 INFO - 'log_to_console': True, 04:26:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:26:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:26:25 INFO - 'opt_config_files': (), 04:26:25 INFO - 'pip_index': False, 04:26:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:26:25 INFO - 'cmd': ('bash', 04:26:25 INFO - '-c', 04:26:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:26:25 INFO - 'enabled': True, 04:26:25 INFO - 'halt_on_failure': False, 04:26:25 INFO - 'name': 'check_screen_resolution'},), 04:26:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:26:25 INFO - 'cmd': ('bash', 04:26:25 INFO - '-c', 04:26:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:26:25 INFO - 'enabled': True, 04:26:25 INFO - 'halt_on_failure': False, 04:26:25 INFO - 'name': 'check_screen_resolution'},), 04:26:25 INFO - 'run_cmd_checks_enabled': True, 04:26:25 INFO - 'suite': 'dromaeojs', 04:26:25 INFO - 'system_bits': '32', 04:26:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:26:25 INFO - 'title': 't-yosemite-r7-0351', 04:26:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:26:25 INFO - 'use_talos_json': True, 04:26:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:26:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:26:25 INFO - 'work_dir': 'build'} 04:26:25 INFO - [mozharness: 2017-05-29 11:26:25.371655Z] Running clobber step. 04:26:25 INFO - Running pre-action listener: _resource_record_pre_action 04:26:25 INFO - Running main action method: clobber 04:26:25 INFO - rmtree: /builds/slave/test/build 04:26:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:26:29 INFO - Running post-action listener: _resource_record_post_action 04:26:29 INFO - [mozharness: 2017-05-29 11:26:29.046625Z] Finished clobber step (success) 04:26:29 INFO - [mozharness: 2017-05-29 11:26:29.046748Z] Running read-buildbot-config step. 04:26:29 INFO - Running pre-action listener: _resource_record_pre_action 04:26:29 INFO - Running main action method: read_buildbot_config 04:26:29 INFO - Using buildbot properties: 04:26:29 INFO - { 04:26:29 INFO - "project": "", 04:26:29 INFO - "product": "firefox", 04:26:29 INFO - "script_repo_revision": "production", 04:26:29 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 04:26:29 INFO - "repository": "", 04:26:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 04:26:29 INFO - "buildid": "20170529025116", 04:26:29 INFO - "pgo_build": "False", 04:26:29 INFO - "basedir": "/builds/slave/test", 04:26:29 INFO - "buildnumber": 4, 04:26:29 INFO - "slavename": "t-yosemite-r7-0351", 04:26:29 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:26:29 INFO - "platform": "yosemite_r7", 04:26:29 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:26:29 INFO - "branch": "mozilla-beta", 04:26:29 INFO - "repo_path": "releases/mozilla-beta", 04:26:29 INFO - "revision": "715d88d0bd826c9e4a5403d6561a2fcc229dd208", 04:26:29 INFO - "stage_platform": "macosx64", 04:26:29 INFO - "builduid": "79fe63c8b69a40189082b1314cc4623f", 04:26:29 INFO - "slavebuilddir": "test" 04:26:29 INFO - } 04:26:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 04:26:29 INFO - Running post-action listener: _resource_record_post_action 04:26:29 INFO - [mozharness: 2017-05-29 11:26:29.050292Z] Finished read-buildbot-config step (success) 04:26:29 INFO - [mozharness: 2017-05-29 11:26:29.050412Z] Running download-and-extract step. 04:26:29 INFO - Running pre-action listener: _resource_record_pre_action 04:26:29 INFO - Running main action method: download_and_extract 04:26:29 INFO - mkdir: /builds/slave/test/build/tests 04:26:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:29 INFO - trying https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 04:26:29 INFO - Downloading https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 04:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 04:26:31 INFO - Downloaded 1287 bytes. 04:26:31 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 04:26:31 INFO - Using the following test package requirements: 04:26:31 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:26:31 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 04:26:31 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:26:31 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:26:31 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 04:26:31 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:26:31 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 04:26:31 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:26:31 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:26:31 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 04:26:31 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:26:31 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:26:31 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 04:26:31 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:26:31 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 04:26:31 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:26:31 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 04:26:31 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:26:31 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 04:26:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 04:26:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:26:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:26:31 INFO - Fetch https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:26:32 INFO - Expected file size: 51393466 04:26:32 INFO - Obtained file size: 51393466 04:26:48 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:26:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:26:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:26:48 INFO - Fetch https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:27:20 INFO - retry: attempt #1 caught BadStatusLine exception: '' 04:27:20 INFO - retry: Failed, sleeping 30 seconds before retrying 04:27:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #2 04:27:50 INFO - Fetch https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:27:52 INFO - Expected file size: 51393466 04:27:52 INFO - Obtained file size: 51393466 04:28:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 04:28:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 04:28:07 INFO - Fetch https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 04:28:08 INFO - Expected file size: 14404461 04:28:08 INFO - Obtained file size: 14404461 04:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:08 INFO - trying https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:28:08 INFO - Downloading https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 04:28:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 04:28:10 INFO - Downloaded 59646626 bytes. 04:28:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:28:10 INFO - mkdir: /builds/slave/test/properties 04:28:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:28:10 INFO - Writing to file /builds/slave/test/properties/build_url 04:28:10 INFO - Contents: 04:28:10 INFO - build_url:https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:28:11 INFO - Running post-action listener: _resource_record_post_action 04:28:11 INFO - Running post-action listener: set_extra_try_arguments 04:28:11 INFO - [mozharness: 2017-05-29 11:28:11.263857Z] Finished download-and-extract step (success) 04:28:11 INFO - [mozharness: 2017-05-29 11:28:11.264038Z] Running populate-webroot step. 04:28:11 INFO - Running pre-action listener: _resource_record_pre_action 04:28:11 INFO - Running main action method: populate_webroot 04:28:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:28:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:28:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:28:11 INFO - '--tppagecycles', 04:28:11 INFO - '7'], 04:28:11 INFO - 'tests': ['tsvgm']}}, 04:28:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:28:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:28:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:28:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:28:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:28:11 INFO - 'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:28:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:28:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:28:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:28:11 INFO - 'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['damp', 'tps']}, 04:28:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:28:11 INFO - 'tests': ['damp', 'tps']}, 04:28:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['dromaeo_dom']}, 04:28:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:28:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['basic_compositor_video']}, 04:28:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:28:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['a11yr', 04:28:11 INFO - 'ts_paint', 04:28:11 INFO - 'tpaint', 04:28:11 INFO - 'sessionrestore', 04:28:11 INFO - 'sessionrestore_no_auto_restore', 04:28:11 INFO - 'tabpaint']}, 04:28:11 INFO - 'other-e10s': {'tests': ['a11yr', 04:28:11 INFO - 'ts_paint', 04:28:11 INFO - 'tpaint', 04:28:11 INFO - 'sessionrestore', 04:28:11 INFO - 'sessionrestore_no_auto_restore', 04:28:11 INFO - 'tabpaint']}, 04:28:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:28:11 INFO - 'ts_paint', 04:28:11 INFO - 'tpaint', 04:28:11 INFO - 'sessionrestore', 04:28:11 INFO - 'sessionrestore_no_auto_restore', 04:28:11 INFO - 'tabpaint']}, 04:28:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:28:11 INFO - 'ts_paint', 04:28:11 INFO - 'tpaint', 04:28:11 INFO - 'sessionrestore', 04:28:11 INFO - 'sessionrestore_no_auto_restore', 04:28:11 INFO - 'tabpaint']}, 04:28:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['a11yr', 04:28:11 INFO - 'ts_paint', 04:28:11 INFO - 'tpaint', 04:28:11 INFO - 'sessionrestore', 04:28:11 INFO - 'sessionrestore_no_auto_restore', 04:28:11 INFO - 'tabpaint']}, 04:28:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['a11yr', 04:28:11 INFO - 'ts_paint', 04:28:11 INFO - 'tpaint', 04:28:11 INFO - 'sessionrestore', 04:28:11 INFO - 'sessionrestore_no_auto_restore', 04:28:11 INFO - 'tabpaint']}, 04:28:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['tsvgx', 04:28:11 INFO - 'tsvgr_opacity', 04:28:11 INFO - 'tart', 04:28:11 INFO - 'tscrollx', 04:28:11 INFO - 'cart', 04:28:11 INFO - 'tsvg_static']}, 04:28:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:28:11 INFO - 'tsvgr_opacity', 04:28:11 INFO - 'tart', 04:28:11 INFO - 'tscrollx', 04:28:11 INFO - 'cart', 04:28:11 INFO - 'tsvg_static']}, 04:28:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:28:11 INFO - 'talos_options': ['--disable-e10s'], 04:28:11 INFO - 'tests': ['tp5o']}, 04:28:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:28:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:28:11 INFO - 'talos_options': ['--disable-e10s', 04:28:11 INFO - '--xperf_path', 04:28:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:28:11 INFO - 'tests': ['tp5n']}, 04:28:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:28:11 INFO - 'talos_options': ['--xperf_path', 04:28:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:28:11 INFO - 'tests': ['tp5n']}}, 04:28:11 INFO - 'talos.zip': {'path': '', 04:28:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:28:11 INFO - Running post-action listener: _resource_record_post_action 04:28:11 INFO - [mozharness: 2017-05-29 11:28:11.284092Z] Finished populate-webroot step (success) 04:28:11 INFO - [mozharness: 2017-05-29 11:28:11.284240Z] Running create-virtualenv step. 04:28:11 INFO - Running pre-action listener: _resource_record_pre_action 04:28:11 INFO - Running main action method: create_virtualenv 04:28:11 INFO - Creating virtualenv /builds/slave/test/build/venv 04:28:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:28:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:28:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:28:11 INFO - Using real prefix '/tools/python27' 04:28:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:28:12 INFO - Installing distribute.............................................................................................................................................................................................done. 04:28:15 INFO - Installing pip.................done. 04:28:15 INFO - Return code: 0 04:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:28:15 INFO - Reading from file tmpfile_stdout 04:28:15 INFO - Output received: 04:28:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:28:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:28:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:15 INFO - 'HOME': '/Users/cltbld', 04:28:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:15 INFO - 'LOGNAME': 'cltbld', 04:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:15 INFO - 'MOZ_NO_REMOTE': '1', 04:28:15 INFO - 'NO_EM_RESTART': '1', 04:28:15 INFO - 'PAGER': '/bin/cat', 04:28:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:15 INFO - 'PWD': '/builds/slave/test', 04:28:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:15 INFO - 'SHELL': '/bin/bash', 04:28:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:15 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:15 INFO - 'USER': 'cltbld', 04:28:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:15 INFO - 'XPC_FLAGS': '0x0', 04:28:15 INFO - 'XPC_SERVICE_NAME': '0', 04:28:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:28:15 INFO - Cleaning up... 04:28:15 INFO - Return code: 0 04:28:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:28:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:15 INFO - 'HOME': '/Users/cltbld', 04:28:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:15 INFO - 'LOGNAME': 'cltbld', 04:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:15 INFO - 'MOZ_NO_REMOTE': '1', 04:28:15 INFO - 'NO_EM_RESTART': '1', 04:28:15 INFO - 'PAGER': '/bin/cat', 04:28:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:15 INFO - 'PWD': '/builds/slave/test', 04:28:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:15 INFO - 'SHELL': '/bin/bash', 04:28:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:15 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:15 INFO - 'USER': 'cltbld', 04:28:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:15 INFO - 'XPC_FLAGS': '0x0', 04:28:15 INFO - 'XPC_SERVICE_NAME': '0', 04:28:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:15 INFO - Downloading/unpacking psutil>=3.1.1 04:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:28:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:28:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:28:18 INFO - Installing collected packages: psutil 04:28:18 INFO - Running setup.py install for psutil 04:28:18 INFO - building 'psutil._psutil_osx' extension 04:28:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:28:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:28:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:28:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:28:18 INFO - building 'psutil._psutil_posix' extension 04:28:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:28:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:28:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:28:18 INFO - ^ 04:28:18 INFO - 1 warning generated. 04:28:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:28:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:28:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:28:18 INFO - Successfully installed psutil 04:28:18 INFO - Cleaning up... 04:28:18 INFO - Return code: 0 04:28:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:18 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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:28:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:28:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:18 INFO - 'HOME': '/Users/cltbld', 04:28:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:18 INFO - 'LOGNAME': 'cltbld', 04:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:18 INFO - 'MOZ_NO_REMOTE': '1', 04:28:18 INFO - 'NO_EM_RESTART': '1', 04:28:18 INFO - 'PAGER': '/bin/cat', 04:28:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:18 INFO - 'PWD': '/builds/slave/test', 04:28:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:18 INFO - 'SHELL': '/bin/bash', 04:28:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:18 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:18 INFO - 'USER': 'cltbld', 04:28:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:18 INFO - 'XPC_FLAGS': '0x0', 04:28:18 INFO - 'XPC_SERVICE_NAME': '0', 04:28:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:28:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:28:20 INFO - Installing collected packages: mozsystemmonitor 04:28:20 INFO - Running setup.py install for mozsystemmonitor 04:28:20 INFO - Successfully installed mozsystemmonitor 04:28:20 INFO - Cleaning up... 04:28:20 INFO - Return code: 0 04:28:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:20 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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:20 INFO - 'HOME': '/Users/cltbld', 04:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:20 INFO - 'LOGNAME': 'cltbld', 04:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:20 INFO - 'MOZ_NO_REMOTE': '1', 04:28:20 INFO - 'NO_EM_RESTART': '1', 04:28:20 INFO - 'PAGER': '/bin/cat', 04:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:20 INFO - 'PWD': '/builds/slave/test', 04:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:20 INFO - 'SHELL': '/bin/bash', 04:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:20 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:20 INFO - 'USER': 'cltbld', 04:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:20 INFO - 'XPC_FLAGS': '0x0', 04:28:20 INFO - 'XPC_SERVICE_NAME': '0', 04:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:21 INFO - Downloading/unpacking jsonschema==2.5.1 04:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:28:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:28:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:28:22 INFO - warning: no files found matching '*.txt' 04:28:22 INFO - no previously-included directories found matching 'build' 04:28:22 INFO - no previously-included directories found matching 'dist' 04:28:22 INFO - no previously-included directories found matching '.git*' 04:28:22 INFO - Installing collected packages: jsonschema, functools32 04:28:22 INFO - Running setup.py install for functools32 04:28:22 INFO - warning: no files found matching '*.txt' 04:28:22 INFO - no previously-included directories found matching 'build' 04:28:22 INFO - no previously-included directories found matching 'dist' 04:28:22 INFO - no previously-included directories found matching '.git*' 04:28:23 INFO - Successfully installed jsonschema functools32 04:28:23 INFO - Cleaning up... 04:28:23 INFO - Return code: 0 04:28:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:28:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:28:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:23 INFO - 'HOME': '/Users/cltbld', 04:28:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:23 INFO - 'LOGNAME': 'cltbld', 04:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:23 INFO - 'MOZ_NO_REMOTE': '1', 04:28:23 INFO - 'NO_EM_RESTART': '1', 04:28:23 INFO - 'PAGER': '/bin/cat', 04:28:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:23 INFO - 'PWD': '/builds/slave/test', 04:28:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:23 INFO - 'SHELL': '/bin/bash', 04:28:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:23 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:23 INFO - 'USER': 'cltbld', 04:28:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:23 INFO - 'XPC_FLAGS': '0x0', 04:28:23 INFO - 'XPC_SERVICE_NAME': '0', 04:28:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:28:23 INFO - Cleaning up... 04:28:23 INFO - Return code: 0 04:28:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:28:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:28:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:23 INFO - 'HOME': '/Users/cltbld', 04:28:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:23 INFO - 'LOGNAME': 'cltbld', 04:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:23 INFO - 'MOZ_NO_REMOTE': '1', 04:28:23 INFO - 'NO_EM_RESTART': '1', 04:28:23 INFO - 'PAGER': '/bin/cat', 04:28:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:23 INFO - 'PWD': '/builds/slave/test', 04:28:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:23 INFO - 'SHELL': '/bin/bash', 04:28:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:23 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:23 INFO - 'USER': 'cltbld', 04:28:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:23 INFO - 'XPC_FLAGS': '0x0', 04:28:23 INFO - 'XPC_SERVICE_NAME': '0', 04:28:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:24 INFO - Downloading blobuploader-1.2.4.tar.gz 04:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:28:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:28:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:25 INFO - Downloading docopt-0.6.1.tar.gz 04:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:28:25 INFO - Installing collected packages: blobuploader, requests, docopt 04:28:25 INFO - Running setup.py install for blobuploader 04:28:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:28:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:28:26 INFO - Running setup.py install for requests 04:28:26 INFO - Running setup.py install for docopt 04:28:26 INFO - Successfully installed blobuploader requests docopt 04:28:26 INFO - Cleaning up... 04:28:26 INFO - Return code: 0 04:28:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:26 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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:26 INFO - 'HOME': '/Users/cltbld', 04:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:26 INFO - 'LOGNAME': 'cltbld', 04:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:26 INFO - 'MOZ_NO_REMOTE': '1', 04:28:26 INFO - 'NO_EM_RESTART': '1', 04:28:26 INFO - 'PAGER': '/bin/cat', 04:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:26 INFO - 'PWD': '/builds/slave/test', 04:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:26 INFO - 'SHELL': '/bin/bash', 04:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:26 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:26 INFO - 'USER': 'cltbld', 04:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:26 INFO - 'XPC_FLAGS': '0x0', 04:28:26 INFO - 'XPC_SERVICE_NAME': '0', 04:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-WNR338-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-g_Dhh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-XQsZ5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-dfA78r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-F9mby9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-w7eXQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Lr32av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Gk22F_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:28:27 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-CKRCv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:28:28 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-rPofkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:28:28 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-I00amp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:28:28 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-JsMtHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:28:28 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-LPXoWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:28:28 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-43LLyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:28 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-SGT46b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:28:28 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-mkC7AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:28:28 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-KEwXic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:28:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:28:28 INFO - Running setup.py install for manifestparser 04:28:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:28:28 INFO - Running setup.py install for mozcrash 04:28:29 INFO - Running setup.py install for mozdebug 04:28:29 INFO - Running setup.py install for mozdevice 04:28:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:28:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:28:29 INFO - Running setup.py install for mozfile 04:28:29 INFO - Running setup.py install for mozhttpd 04:28:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:28:29 INFO - Running setup.py install for mozinfo 04:28:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:28:29 INFO - Running setup.py install for mozInstall 04:28:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Running setup.py install for mozleak 04:28:30 INFO - Running setup.py install for mozlog 04:28:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Running setup.py install for moznetwork 04:28:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Running setup.py install for mozprocess 04:28:30 INFO - Running setup.py install for mozprofile 04:28:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:28:30 INFO - Running setup.py install for mozrunner 04:28:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:28:31 INFO - Running setup.py install for mozscreenshot 04:28:31 INFO - Running setup.py install for moztest 04:28:31 INFO - Running setup.py install for mozversion 04:28:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:28:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:28:31 INFO - Cleaning up... 04:28:31 INFO - Return code: 0 04:28:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:31 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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:28:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:31 INFO - 'HOME': '/Users/cltbld', 04:28:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:31 INFO - 'LOGNAME': 'cltbld', 04:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:31 INFO - 'MOZ_NO_REMOTE': '1', 04:28:31 INFO - 'NO_EM_RESTART': '1', 04:28:31 INFO - 'PAGER': '/bin/cat', 04:28:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:31 INFO - 'PWD': '/builds/slave/test', 04:28:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:31 INFO - 'SHELL': '/bin/bash', 04:28:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:31 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:31 INFO - 'USER': 'cltbld', 04:28:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:31 INFO - 'XPC_FLAGS': '0x0', 04:28:31 INFO - 'XPC_SERVICE_NAME': '0', 04:28:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:28:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-4zTRe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:28:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:28:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-uAnvy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:28:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:28:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Hdn3fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-KMryWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-y9tZE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-gUbJZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-6FJ7yE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-og4KGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-rq1sxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-OgVXMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-nYXIe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:28:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-26EDdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:28:33 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-p80rGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:28:33 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Lf21_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:33 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-zHv9bW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:28:33 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-dbtEHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:28:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:28:33 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-IwjSno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:28:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:34 INFO - Downloading blessings-1.6.tar.gz 04:28:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:28:35 INFO - Installing collected packages: blessings 04:28:35 INFO - Running setup.py install for blessings 04:28:35 INFO - Successfully installed blessings 04:28:35 INFO - Cleaning up... 04:28:35 INFO - Return code: 0 04:28:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:28:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:28:35 INFO - Reading from file tmpfile_stdout 04:28:35 INFO - Current package versions: 04:28:35 INFO - blessings == 1.6 04:28:35 INFO - blobuploader == 1.2.4 04:28:35 INFO - docopt == 0.6.1 04:28:35 INFO - functools32 == 3.2.3-2 04:28:35 INFO - jsonschema == 2.5.1 04:28:35 INFO - manifestparser == 1.1 04:28:35 INFO - mozInstall == 1.12 04:28:35 INFO - mozcrash == 1.0 04:28:35 INFO - mozdebug == 0.1 04:28:35 INFO - mozdevice == 0.49 04:28:35 INFO - mozfile == 1.2 04:28:35 INFO - mozhttpd == 0.7 04:28:35 INFO - mozinfo == 0.9 04:28:35 INFO - mozleak == 0.1 04:28:35 INFO - mozlog == 3.4 04:28:35 INFO - moznetwork == 0.27 04:28:35 INFO - mozprocess == 0.25 04:28:35 INFO - mozprofile == 0.28 04:28:35 INFO - mozrunner == 6.13 04:28:35 INFO - mozscreenshot == 0.1 04:28:35 INFO - mozsystemmonitor == 0.3 04:28:35 INFO - moztest == 0.8 04:28:35 INFO - mozversion == 1.4 04:28:35 INFO - psutil == 3.1.1 04:28:35 INFO - requests == 1.2.3 04:28:35 INFO - wsgiref == 0.1.2 04:28:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:35 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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:35 INFO - 'HOME': '/Users/cltbld', 04:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:35 INFO - 'LOGNAME': 'cltbld', 04:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:35 INFO - 'MOZ_NO_REMOTE': '1', 04:28:35 INFO - 'NO_EM_RESTART': '1', 04:28:35 INFO - 'PAGER': '/bin/cat', 04:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:35 INFO - 'PWD': '/builds/slave/test', 04:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:35 INFO - 'SHELL': '/bin/bash', 04:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:35 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:35 INFO - 'USER': 'cltbld', 04:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:35 INFO - 'XPC_FLAGS': '0x0', 04:28:35 INFO - 'XPC_SERVICE_NAME': '0', 04:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:28:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:28:35 INFO - Cleaning up... 04:28:35 INFO - Return code: 0 04:28:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:35 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 0x1007d6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f9d7ffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, '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.K06SeZ4f48/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.n9uqKclqyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:35 INFO - 'HOME': '/Users/cltbld', 04:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:35 INFO - 'LOGNAME': 'cltbld', 04:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:35 INFO - 'MOZ_NO_REMOTE': '1', 04:28:35 INFO - 'NO_EM_RESTART': '1', 04:28:35 INFO - 'PAGER': '/bin/cat', 04:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:35 INFO - 'PWD': '/builds/slave/test', 04:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:35 INFO - 'SHELL': '/bin/bash', 04:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:35 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:35 INFO - 'USER': 'cltbld', 04:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:35 INFO - 'XPC_FLAGS': '0x0', 04:28:35 INFO - 'XPC_SERVICE_NAME': '0', 04:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:28:36 INFO - Cleaning up... 04:28:36 INFO - Return code: 0 04:28:36 INFO - Running post-action listener: _resource_record_post_action 04:28:36 INFO - Running post-action listener: _start_resource_monitoring 04:28:36 INFO - Starting resource monitoring. 04:28:36 INFO - [mozharness: 2017-05-29 11:28:36.153305Z] Finished create-virtualenv step (success) 04:28:36 INFO - [mozharness: 2017-05-29 11:28:36.154147Z] Running install step. 04:28:36 INFO - Running pre-action listener: _resource_record_pre_action 04:28:36 INFO - Running main action method: install 04:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:28:36 INFO - Reading from file tmpfile_stdout 04:28:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:28:36 INFO - Reading from file tmpfile_stdout 04:28:36 INFO - Output received: 04:28:36 INFO - Usage: mozinstall [options] installer 04:28:36 INFO - Options: 04:28:36 INFO - -h, --help show this help message and exit 04:28:36 INFO - -d DEST, --destination=DEST 04:28:36 INFO - Directory to install application into. [default: 04:28:36 INFO - "/builds/slave/test"] 04:28:36 INFO - --app=APP Application being installed. [default: firefox] 04:28:36 INFO - mkdir: /builds/slave/test/build/application 04:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:28:51 INFO - Reading from file tmpfile_stdout 04:28:51 INFO - Output received: 04:28:51 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:28:51 INFO - Running post-action listener: _resource_record_post_action 04:28:51 INFO - [mozharness: 2017-05-29 11:28:51.097247Z] Finished install step (success) 04:28:51 INFO - [mozharness: 2017-05-29 11:28:51.097402Z] Running run-tests step. 04:28:51 INFO - Running pre-action listener: _resource_record_pre_action 04:28:51 INFO - Running main action method: run_tests 04:28:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:28:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:28:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:28:51 INFO - 2017-05-29 04:28:51.111 screenresolution[2370:78290] starting screenresolution argv=screenresolution get 04:28:51 INFO - 2017-05-29 04:28:51.114 screenresolution[2370:78290] Display 0: 1600x1200x32@60 04:28:51 INFO - 2017-05-29 04:28:51.120 screenresolution[2371:78292] starting screenresolution argv=screenresolution list 04:28:51 INFO - Available Modes on Display 0 04:28:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:28:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:28:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:28:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:28:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:28:51 INFO - Intel Iris: 04:28:51 INFO - Chipset Model: Intel Iris 04:28:51 INFO - Type: GPU 04:28:51 INFO - Bus: Built-In 04:28:51 INFO - VRAM (Dynamic, Max): 1536 MB 04:28:51 INFO - Vendor: Intel (0x8086) 04:28:51 INFO - Device ID: 0x0a2e 04:28:51 INFO - Revision ID: 0x0009 04:28:51 INFO - Displays: 04:28:51 INFO - Display: 04:28:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:28:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:28:51 INFO - Display Serial Number: 1600x1200 60 04:28:51 INFO - Main Display: Yes 04:28:51 INFO - Mirror: Off 04:28:51 INFO - Online: Yes 04:28:51 INFO - Rotation: Supported 04:28:51 INFO - Return code: 0 04:28:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:28:51 INFO - Python 2.7.3 04:28:51 INFO - Return code: 0 04:28:51 INFO - grabbing minidump binary from tooltool 04: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')]} 04:28:51 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 0x1005b4d30>, '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 0x1004dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005a9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:28:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:28:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:28:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:28:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:28:51 INFO - Return code: 0 04:28:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:28:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:28:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:28:51 INFO - ENV: RUST_BACKTRACE is now 1 04:28:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:28:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:28:51 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/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:28:51 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/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0351 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K06SeZ4f48/Render', 04:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:51 INFO - 'HOME': '/Users/cltbld', 04:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:51 INFO - 'LOGNAME': 'cltbld', 04:28:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:51 INFO - 'MOZ_NO_REMOTE': '1', 04:28:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:51 INFO - 'NO_EM_RESTART': '1', 04:28:51 INFO - 'PAGER': '/bin/cat', 04:28:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:51 INFO - 'PWD': '/builds/slave/test', 04:28:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:51 INFO - 'RUST_BACKTRACE': '1', 04:28:51 INFO - 'SHELL': '/bin/bash', 04:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n9uqKclqyZ/Listeners', 04:28:51 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 04:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:51 INFO - 'USER': 'cltbld', 04:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:51 INFO - 'XPC_FLAGS': '0x0', 04:28:51 INFO - 'XPC_SERVICE_NAME': '0', 04:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:51 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/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:28:51 INFO - mozversion application_buildid: 20170529025116 04:28:51 INFO - mozversion application_changeset: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 04:28:51 INFO - mozversion application_display_name: Firefox 04:28:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:28:51 INFO - mozversion application_name: Firefox 04:28:51 INFO - mozversion application_remotingname: firefox 04:28:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:28:51 INFO - mozversion application_vendor: Mozilla 04:28:51 INFO - mozversion application_version: 54.0 04:28:51 INFO - mozversion platform_buildid: 20170529025116 04:28:51 INFO - mozversion platform_changeset: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 04:28:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:28:51 INFO - mozversion platform_version: 54.0 04:28:51 INFO - using testdate: 1496057331 04:28:51 INFO - actual date: 1496057331 04:28:51 INFO - starting webserver on 'localhost:60295' 04:28:51 INFO - SUITE-START | Running 2 tests 04:28:51 INFO - TEST-START | dromaeo_css 04:28:51 INFO - operating with platform_type : mac_ 04:28:51 INFO - Initialising browser for dromaeo_css test... 04:28:51 INFO - TEST-INFO | started process 2381 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpd5oYg8/profile http://localhost:60295/getInfo.html) 04:28:53 INFO - PROCESS | 2381 | __metrics Screen width/height:1600/1200 04:28:53 INFO - PROCESS | 2381 | colorDepth:24 04:28:53 INFO - PROCESS | 2381 | Browser inner width/height: 1024/689 04:28:53 INFO - PROCESS | 2381 | __metrics 04:28:54 INFO - PROCESS | 2381 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:28:54 INFO - PROCESS | 2381 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:28:54 INFO - TEST-INFO | 2381: exit 0 04:28:54 INFO - Browser initialized. 04:28:54 INFO - Running cycle 1/1 for dromaeo_css test... 04:28:54 INFO - TEST-INFO | started process 2384 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpd5oYg8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:29:06 INFO - PROCESS | 2384 | RSS: Main: 165027840 04:29:06 INFO - PROCESS | 2384 | 04:32:10 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60295/tests/dromaeo/cssquery-ext.html) 04:32:11 INFO - PROCESS | 2384 | RSS: Main: 286973952 04:32:11 INFO - PROCESS | 2384 | 04:35:14 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-ext.html (next: http://localhost:60295/tests/dromaeo/cssquery-jquery.html) 04:35:15 INFO - PROCESS | 2384 | RSS: Main: 302272512 04:35:15 INFO - PROCESS | 2384 | 04:38:17 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60295/tests/dromaeo/cssquery-mootools.html) 04:38:18 INFO - PROCESS | 2384 | RSS: Main: 287645696 04:38:18 INFO - PROCESS | 2384 | 04:41:20 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60295/tests/dromaeo/cssquery-prototype.html) 04:41:21 INFO - PROCESS | 2384 | RSS: Main: 275726336 04:41:21 INFO - PROCESS | 2384 | 04:44:24 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60295/tests/dromaeo/cssquery-yui.html) 04:44:25 INFO - PROCESS | 2384 | RSS: Main: 267313152 04:44:25 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-yui.html (next: http://localhost:60295/tests/dromaeo/cssquery-dojo.html) 04:47:28 INFO - PROCESS | 2384 | RSS: Main: 303804416 04:47:28 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | __start_tp_report 04:47:28 INFO - PROCESS | 2384 | _x_x_mozilla_page_load 04:47:28 INFO - PROCESS | 2384 | _x_x_mozilla_page_load_details 04:47:28 INFO - PROCESS | 2384 | |i|pagename|runs| 04:47:28 INFO - PROCESS | 2384 | |0;dojo.html;2991.326951435927;3475.2701932594155;3495.685388315053;3540.6990405815504;3543.901390568924;6011.849703757408;6070.089486577016;6082.635041897478;6091.116788065733;6144.69276536173;5996;6085.847853803657;6089.6955152242435;6095.329513753483;6109.327973922865;5384.111621582434;5442.591805614575;5445.1832225166145;5448.101063324547;5543.279373681436;5346.946530534703;5350.170723537858;5381.569474442055;5445.319335083114;5584.4136365681625;5517.724113794315;5527.447255274481;5533.501984821365;5574.1917421973785;5597.216389705444;5498.1752737089355;5534.335879694422;5535.778568857261;5540.722963851791;5550.389457159709;5352.785888564453;5472.206530053159;5479.945200547983;5527.917081243784;5532.972335138298;4972.801087956514;5893.941060589425;5928.614640048383;5989.670568118751;6080.270367555878;6357.618542887395;6371.745130194787;6376.744930202786;6377.8405539861415;6379.043143528462;6317.4946004319545;6428.421442070189;6456.48348132153;6458.709358078898;6485.448736857373;5967.910481342783;5989.610675306091;6089.086637004485;6216.2851272103835;6224.097505861625;5368.194770784414;6388.073729309224;6410.326915673881;6483.384078512629;6603.933960660428;6282.685865706696;6306.400891915261;6325.588836725598;6335.936640633535;6336.239651241881;6380.3619638035825;6443.613383197023;6450.8064758057335;6454.031895215661;6473.06140609609;6262.935300998841;6313.747450102036;6351.587146835441;6365.267994180728;6372.044193371032;6301.968490157612;6313.873722525524;6365.649889256136;6372.968135159294;6383.489320854321;5192.376914770176;5238.188080847474;5247.239150323258;5257.08001099806;5261.736913154327;4593.242115050979;4676.649251306166;4700.08348372071;4700.341952126705;4724.196886529298;6059.909101363483;6073.210482637229;6096.542759293071;6099.146119543268;6103.42017508336;6319.115323854664;6333.556651034383;6348.365163483707;6348.428641422294;6349.777757778455;6096.69516524172;6110.266767987781;6181.907271390933;6184.319724830265;6187.412195841388;5699.202111704365;5706.4864162225595;5711.600187986801;5720.942790572041;5723.456271654186;6645.259253727551;6665.166854143232;6676.26561078281;6695.698693558801;6702.430293425114;6257.2491301044165;6294.842628934313;6365.9681701593045;6391.7123729433215;6451.483881289391;5764.827055188351;6677.465802735866;6720.6975686093265;6720.764773232913;6721.462283017249;6196.690165491797;6365.267994180544;6376.3623637635865;6395.16862807832;6398.808035758934;6253.280872699698;6295.370462953668;6342.936570634419;6456.741730330734;6471.741130354922;5256.316678831728;5306.708131052675;6272.560920735505;6281.905771413341;6345.333739957238;6346.9682651586445;6346.9682651586445;6347.714352854225;6351.68241587928;6356.872862542816;6109.877802444015;6166.136740856194;6277.497800176066;6291.339409362135;6372.55392122547;6246.281677607133;6288.465480434214;6317.810465686037;6326.2092238470195;6329.525285603506;6331;6332.113504109337;6344.524160687874;6352.460040896575;6360.141380913517 04:47:28 INFO - PROCESS | 2384 | |1;ext.html;815.9224873637004;829.005193767479;898.5372533145431;913.7121806630915;915.354674954157;41324.793376033114;42036.57963420361;42125.73622791317;42153.94615134623;42158.57841421585;34661.826690866576;42630.36054459186;42943.85280735966;43084.92287692809;43175;7977.760667179994;9615.567299471522;9787.265955053364;9795.902040979607;9808.852867206997;8069.314108300801;11805.232659877123;11997.40012999351;12253.387330633477;12255;12000.399980000964;12061.27632342058;12066.57766978156;12072.094592905523;12108;11952.521899124025;11995.340256285901;12005.639830805003;12023.977961873252;12026.917577418031;11569.884301156964;11608;11610.477528511237;11619.477123529461;11630.30218186903;40267.798661006804;40417;40444.393334100016;40450.19099617991;40631.18737625261;3958.6437220650278;3972.145988612462;3982.38273067675;3985.900352491182;4026.838926442939;28269.010584629646;28539;28579.857100714362;29109;30215.244618884484;3135.9372812543847;3250.777846919726;3291.078498020558;3318.883839065645;3423.2982238641266;355417;357313;357715;357752;358195.9999999974;52756.47243527592;53356.19965700518;53456.19815702768;53471.19793203027;53531;55519.44480555224;55588;55604.72197639067;55634.88730225373;55644.443555564736;13113;15061.924690376478;15546.300416481285;15547.611309717122;15579.688406231811;13356.532521361705;13428.462861485626;13445.46218151263;13459.327033648278;13492.325383730971;9935.701928942144;12098.576549820715;12273.018158547295;12284.078694097978;12403.25580465175;5222.529972302511;5266.552343050807;5273.3935597406025;5277.683338999673;5293.973530132402;941.1388579449726;968.7917097824002;973.3673112476947;982.6708052802248;983.1544871410581;28664.426711465654;29587.964421245153;29612.851935740182;30238.395232095674;30380.848095759822;1049.8372752223418;1054.3884546962743;1067.5889782433665;1091.7434402915467;1093.7429704019548;11582.88417115835;15320.693586128215;15327.693446131016;15523.767143492856;15655.373785048698;424776;424895;426937;427290;427561.86219069327;57302.426975730545;57419;57430.7128464355;57468.71265643645;57632.42367576271;1269.7206614544784;1440.819897512811;1478.6820833520844;1495.8055452791068;1499.14548707238;18911.497297664508;28158.2960425991;28205.15384538467;28730.856345718974;28763.71236287694;46002.30996534988;46296.07407851891;46334.30498542592;46376.30435543537;46444.99999999865;450600.7469962629;453985;453996;454381;455398;24457.75542244555;24785.380365491008;24857.627135593342;25466.872665636554;29072.83708651715;31214;31397.8430107848;38123.83557299657;38598.80700596479;38629.806850965564;24705.236360005416;26042;27275.045373411835;28618.85690571534;28641.713582863904;27075.458490830468;27478.313042173308;27758.583621245292;28128.29679258035;28378.148655540368;31345.529817052306;31882.840585796923;32540.83729581337;32650.183745406553;32704.855330063332;27298.454030919667;27340.726592734612;27626.89492420361;27996.44007119887;28397.858010709813;21903.67144492866;22562;22907.656385153903;22912.770872291065;22930.656040159745 04:47:28 INFO - PROCESS | 2384 | |2;jquery.html;429.9573534179614;437.89928316487186;438.3578058144817;441.13097198518915;451.58905396089546;20394.388168354955;21265.787342126594;22436.326910192678;22466.32601021968;22516.77483225167;22391.888040559774;23113.30660080196;23634.290971270846;23755.5248895022;23844.926978285974;6980.616066116361;6986.231514533397;7033;7109.751158709446;7147.57114573126;6641.335866413368;6953.165620125592;6957.373836354727;6968.930310696879;6972.860542789142;6941.6876240569045;7596.848063038736;7903.249191326815;8097.392695547828;8113.918860811375;7805.5706936118495;7989.680412783482;8034.437589368747;8083.8787418188185;8113.716019939303;7549.660265288076;7813.335866451358;7822.4524283299625;7824.139344672081;7827.1390147083775;26711.866440667865;27053.729462705316;27160.592591111148;27195.048173314037;27525.862370688217;2996.1910284223136;3189.997314314697;3467.809270490122;3493.580770307554;3542.6103128655827;15753.842461575352;16132.274047667883;16582.17089145538;16604.169791510496;17280.913595432066;2129.936101916945;2135.124598914453;2142.946426339354;2163.664631982045;2272.2728726807263;84455.76606697404;105703.47148264287;151407;173330;178044.1097794503;20152.69770953437;20396.184152634025;20757;20934.58130837375;21001.159953601684;33059.3388132236;33565.664343356744;33760;33763.83118084394;33824.49263261053;10575.524101415454;10790.622328218475;10980.341179529254;10996.395198264012;11184.44077796107;7617.6572054257695;9064.501452420049;9099.134677391252;9115.316351273548;9294.767630809149;8326.875871757356;9160.312976526788;9522.476263805418;9706.185206290238;9738.610455581838;3128.6390420166845;3844.8654297099465;3862.8841134766003;3866.478025466582;3887.5140607424073;698.9231184569693;823.6170180865926;1006.5269323417982;1053.2679787547581;1105.1269497097203;21268.93655317228;22660;22669;22708.318750437513;22760.610135935818;1058.6400623787795;1066.3122286125406;1076.160594736107;1085.6124075665175;1097.7924283288319;10942.562297508011;11689.240199387013;11700.824487632692;11722.062235021178;11724.531018759324;709557;748762;758816;761911;780485;162919.18540407222;167889;168523;171781.14109429374;174529;1209.8850609192114;1216.0514798457218;1216.7079900823915;1245.9190152640049;1246.9961680846825;18549.814501855348;18691.90654046721;19099.140538675514;19140.8085919139;19322.420327390202;63652.408689781274;63686.681566593725;64228.67885660542;64611.03083453846;66005.66997164984;93548;94323;94621;96074;96987;11610.24533405326;11765.235259708092;11871.703207419883;12229.571964981184;12259.019278457523;13337.933310333774;13396.799048014092;13428.530001449903;13449.731005380034;13472.393742281816;11553.82669259945;11608.709782255512;11640.301581905112;11674.124440667154;11984.76030479403;8988.415752976036;10921.453927303444;10955.50700218476;10977.615783447542;11003.61487347939;11570.363630000431;11724.70688232801;11856.229345092886;11937;12144.33206173669;9861.457619830979;11613.3612651305;11876.287422754662;12103.455344509342;12176.512939482322;9236.907630923604;11652.475638596454;11743.11926605491;11806.173567850168;11948.522059117538 04:47:28 INFO - PROCESS | 2384 | |3;mootools.html;1600.3198640577766;1611.9355225790969;1641.8850680452374;1653.082539190749;1668.549491637258;10468.214883883711;11159.721006974809;11167.050800681951;11172.497237624306;11177.49701263443;10853.131749460044;11017.11863050956;11306.208565400426;11510.539578416874;11629.185956982994;2475.0471068638567;2513.861737604431;2518.3326418499114;2520.5085008423366;2521.1050077222503;2431.3799981004818;2461.544614246361;2511.196417146514;2511.573032584465;2516.484120755241;2462.495188486365;2473.9752602474014;2496.251124662594;2506.1103308325555;2514.270861450186;2436.50051739393;2471.9629205561932;2482.553140434721;2489.4274316907213;2499.6125600531855;2198.483356411242;2376.7997161433536;2453.092355828355;2484.2795589279085;2484.571371461399;11299;11522.539098436053;11531.192816502848;11538.019268362199;11541.769164616744;5928.555358348141;6111.480524155452;6117.113018612321;6136.202293701835;6137.539684523678;3533.593636731783;3559.661832125944;3615.1685112424025;3678.0253232893306;3727.7204209684382;1199.8920097191294;1206.6681662542783;1221.5663439479035;1223.6451429085555;1264.2856785915928;13906.721865562733;14241.572752817432;14619.415223391052;15865.444709435229;16041.35834566616;4264.850730224427;4322.5677432256525;4360.585744354345;4483.3947417098925;4550.658700597469;12113.636590902288;12131.818022729665;12181.269123852597;12186.57346992851;12200.816987745191;6062.120992456069;6065.2418447694035;6078.057901025348;6079.0273556230795;6083.969580152071;5415.0523658359625;5425.565954723613;5468.699221542774;5484.808031718871;5553.611247212737;3916.4908561886773;3986.3023970804993;4017.216642754635;4057.411675307064;4064.4106604542176;910.649399981012;1062.4103622489592;1064.7444613292755;1073.5276478349501;1076.2304951959386;955.6429869585066;981.2493442516338;984.6356847966298;987.4223579206101;1012.0891197921782;6590.934090659032;6627.204735431779;6709.261981182066;6853.485988550879;6858.897116543256;895.6596493332622;988.2983082011853;1022.3712416863724;1031.2626472072354;1059.6820953713855;3692.9076773080888;4216.388623649615;4217.34631132175;4308.202982448194;4462.754548499864;13782.173069615856;14374.85625143756;14377.281135943162;14388;14391.496297629532;13882.444702212;14100.717985640435;14404.983302337681;14413.927930360282;14447.205403702897;8975.910240897638;9278.999999999865;10420.843687344695;10579.682609521727;10634.787304253872;6168.568200226121;6738.090357801634;6965.164180298397;7095.751648692271;7112.039874617068;11881.5841445549;11994.76010479803;12013.699657508632;12225.327606981346;12265.57070502528;12230.938845305716;12289.69275768113;12350.07374446902;12438.751224975631;12459.563915262923;8434.915650843413;8680.565971701264;8803.60383782744;8809.559522024001;8815.382923195315;10415.375077495375;10418.374897506174;10534.473276335999;10560.155187584822;10565.841512377474;8908.420952638317;8917.331200159884;8971;8982.86525702128;9014.459132452073;6973.755918542826;7424.665890034853;8642;8805.867911981453;8806.075362086889;7801.531908085533;8076.030876294647;8166.060902996232;8169.795755105933;8221.876671849808;5974.522038236843;6074.908876366946;6092.355064132748;6100.54245931548;6264.2796078451565;5095.388553373619;5981.850453738692;6215.005599104122;6247.531435142291;6284.402981716729 04:47:28 INFO - PROCESS | 2384 | |4;prototype.html;384.3446922996292;387.3821255098119;393.0998014546688;396.38956007748067;400.68746377825306;4180.393842892787;4475.731456112631;4976.929960058586;4992.525710057548;5050.242463630453;4774.713517188962;4850.563449289563;4957.801687932488;4999.8000079996755;5082.822101226458;4042.272390969624;4176.540580536139;4201.663866890642;4206.789660516977;4220.430241917348;3695.90760230995;4221.535631080594;4222.429971953793;4223.577642235768;4247.299195632732;4157.964685779383;4228.915421691564;4233;4234.87295381139;4278.080212754257;3654.689351405134;4067.1865626874596;4151.045259590311;4184.581541845791;4227.408162857202;3564.4296912494137;3834.4440056191975;3850.1337199130253;3853.98073009636;3858.2189454334907;4438.556144385536;4940.802367905279;4974.676646017997;4988.850334489971;5012.270766585559;4205.558416366299;4320.336670381926;4573.679842411033;4804.159272127364;4812.687175333592;4921.311016457699;5055.873603159914;5257.658252213632;5264.394594621629;5276.704633484446;4067.9389809152885;4182.853600124012;4307.569243075667;4310.82756689732;4318.546552611993;4932.432770231433;4962.925556116661;5085.829619711801;5307.787688492455;5322.119058440407;3710.7735358785053;4351.26028575143;4365.236083685342;4400.481833767345;4417.271150260235;3855.2265957659793;4487.586410280751;4521.892136426531;4551.4765801932535;4566.931496027562;4012.9197416051516;4516.932246016312;4612.76936153191;4806.351142595775;4917;5252.612065217913;5284.894302113936;5334.333208348957;5335.866603334947;5348.9465105348445;1283.7560863435917;1514.8487149766036;1516.673915108257;1521;1526.4275896538797;1012.6455740490769;1071.919606029551;1139.5669645534645;1155.7341811383428;1177.9469923853446;4237.91524169521;5009.574186194214;5010.22341537055;5047.753749581474;5062.493750624908;4952.678075925125;4979.427365852901;5026.81869760606;5284.497972692665;5462.2079798429;3851.9037024074064;4605.424321959755;4766.475687674353;5008.84973450797;5024.522670346311;2597.9610305845426;2865.5958580295924;2874.841883696375;2877;2915.2128925190505;5471.4528547145765;5704.691279058136;5710.914336284959;5720.656760594295;5732.6847023413275;4931.778069986859;5240.580753539708;5273.604479664041;5450;5626.2685850839125;5580.86047848807;5650.85872853182;5652.236948012048;5678.914816277759;5711.314642242957;4682.976585117189;5004.724740139327;5316.2786049764545;5428.402875683592;5565.053940830149;5060.949390506048;5205.531502164823;5416.133418652997;5422.15956526739;5460.590455715757;5222.55608273301;5254.763535640829;5437.619366644297;5694.857628559319;5728.828135155952;4261;4290.742555446684;4360.956390436055;4364.519902810624;4378.584034516716;4542.954570454253;4989.401271847401;4994.076095922266;5480.424555421623;5513.26332205354;4852.296417019583;5133.307003554622;5281.630285879952;5348.384935732441;5384.6769193848495;4601.792919318706;5046.9747651261505;5149.094834911107;5295.299397307324;5313.681179129265;4776.781920610222;4901.848065704486;5046.1673823818655;5143.331366922276;5213.635045546927;4803.759812009455;4879.536444037811;5047.1419858623385;5172.922406163986;5272.499112584298;4370.956290437055;4681.461631912373;4695.812167513398;4790.7365094920115;5194.844154675366 04:47:28 INFO - PROCESS | 2384 | |5;yui.html;761.7219714804098;776.0105865022097;815.1929589706191;815.8572249856273;884.8274586455642;16078;16946.91526542367;16975;16982.830171698264;17004.574885627833;15688.529344119695;15690.450834220805;15690.686186276267;15843.445479408223;15863.524094277162;999.1956475037592;1019.9541020654088;1020.6070662794804;1020.9489525523732;1052.9631462898801;1036.813373592753;1044.858944042556;1045.4668119259195;1045.4720366215051;1046.2989796836139;1091.8525998990115;1093.5789720957448;1094.0973696700214;1095.2880627592049;1095.2935356694927;1077.7144056824948;1078.6170909327154;1079.7246702090995;1082.680609220278;1083.696564961808;806.6410447350931;809.0695699945052;809.3686924199076;810.3314765318636;813.9918600814035;16431.589210269718;16881.15594220284;17259.395921142826;17313.653726925517;17577;11887.643370698894;14520.056196347203;14599.70800583993;14668.339924703414;14691.045082069739;11883;11923.94038029813;12080.939595302056;12106.757864842742;12692.17500862441;3848.2688289224957;3851.499305090348;3879.4568760373572;3895.7467764595212;4026.1545075534173;87608;112788;113926;115282;117048.41475792651;9747.65883194099;11161.776764464828;11168.56770652059;11636.88363116375;11745.823812642817;23115.537689246456;23464.64803027956;23667.644985325234;23913.641295380585;23914.52170956606;8034.317083047889;8040.115587285394;8175.67297308101;8207.343412526983;8449.619767110495;4532.9320060199125;4546.977265113654;4634.235351167087;4667.416572928384;4840.273958906122;3548.6451354864307;3601.225736466672;3603.9099022524647;3605.2248766515327;3606.747527673038;573.237593999979;659.650385295794;684.7397988764237;694.035290945586;698.2947223304499;708.9042979197845;738.2064280898056;755.2108047090803;766.4941138848334;769.4114002787871;10248.948755256177;10755.677329679966;10965.50655220517;11157.94421027906;11294.774104518028;686.3788271614197;698.6017969757189;719.251977942944;741.3513175971025;748.3339827553482;5111.87220319495;5216.139337009351;5227.372715274191;5233.947660523422;5236.92144617831;87922;97528;103922;106754.46622766992;109828;47609;77057;88206.55896720475;88648;93129;58763;77308;79082;79810.60094699489;79831.60084199658;10710.64365839514;10929.726756831144;11208.495617697386;11454.942725286654;11605.30368177912;46686;49408.75295623499;49775.75112124416;50317.49682503128;51514.74242628763;68426.31573684199;69051.30948690449;70980;71028.64485677745;71382.28617713756;10835.349879007284;12929.870701293243;13024;13174.934125329311;13475.865241347461;14292.928535357256;14740.852591473948;14765;15258.465953691568;15388.769168462706;12109.273443593413;13911.165330080228;14405.639859003608;14552.708945821236;15063.397464101314;13620.795688064885;14109.153450792985;14120.78818817698;14416.207108609127;14734.26328683583;13263.270520121487;13853.205086824495;14073.437062517385;14078.436862525385;14129.576112716635;9440.905590944003;10072.496375181065;10135.391876487434;10239.641612543524;10430.478476076014;8270.338372930271;9182.770430739285;9569.473678947725;10390.58437662485;11108.778244351 04:47:28 INFO - PROCESS | 2384 | __end_tp_report 04:47:28 INFO - PROCESS | 2384 | __start_cc_report 04:47:28 INFO - PROCESS | 2384 | _x_x_mozilla_cycle_collect,1838 04:47:28 INFO - PROCESS | 2384 | __end_cc_report 04:47:28 INFO - PROCESS | 2384 | __startTimestamp1496058448689__endTimestamp 04:47:28 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | ------- Summary: start ------- 04:47:28 INFO - PROCESS | 2384 | Number of tests: 6 04:47:28 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | [#0] dojo.html Cycles:165 Average:5968.40 Median:6246.28 stddev:651.76 (10.4%) stddev-sans-first:610.48 04:47:28 INFO - PROCESS | 2384 | Values: 2991.3 3475.3 3495.7 3540.7 3543.9 6011.8 6070.1 6082.6 6091.1 6144.7 5996.0 6085.8 6089.7 6095.3 6109.3 5384.1 5442.6 5445.2 5448.1 5543.3 5346.9 5350.2 5381.6 5445.3 5584.4 5517.7 5527.4 5533.5 5574.2 5597.2 5498.2 5534.3 5535.8 5540.7 5550.4 5352.8 5472.2 5479.9 5527.9 5533.0 4972.8 5893.9 5928.6 5989.7 6080.3 6357.6 6371.7 6376.7 6377.8 6379.0 6317.5 6428.4 6456.5 6458.7 6485.4 5967.9 5989.6 6089.1 6216.3 6224.1 5368.2 6388.1 6410.3 6483.4 6603.9 6282.7 6306.4 6325.6 6335.9 6336.2 6380.4 6443.6 6450.8 6454.0 6473.1 6262.9 6313.7 6351.6 6365.3 6372.0 6302.0 6313.9 6365.6 6373.0 6383.5 5192.4 5238.2 5247.2 5257.1 5261.7 4593.2 4676.6 4700.1 4700.3 4724.2 6059.9 6073.2 6096.5 6099.1 6103.4 6319.1 6333.6 6348.4 6348.4 6349.8 6096.7 6110.3 6181.9 6184.3 6187.4 5699.2 5706.5 5711.6 5720.9 5723.5 6645.3 6665.2 6676.3 6695.7 6702.4 6257.2 6294.8 6366.0 6391.7 6451.5 5764.8 6677.5 6720.7 6720.8 6721.5 6196.7 6365.3 6376.4 6395.2 6398.8 6253.3 6295.4 6342.9 6456.7 6471.7 5256.3 5306.7 6272.6 6281.9 6345.3 6347.0 6347.0 6347.7 6351.7 6356.9 6109.9 6166.1 6277.5 6291.3 6372.6 6246.3 6288.5 6317.8 6326.2 6329.5 6331.0 6332.1 6344.5 6352.5 6360.1 04:47:28 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | [#1] ext.html Cycles:180 Average:55156.99 Median:17266.89 stddev:109829.73 (636.1%) stddev-sans-first:110062.05 04:47:28 INFO - PROCESS | 2384 | Values: 815.9 829.0 898.5 913.7 915.4 41324.8 42036.6 42125.7 42153.9 42158.6 34661.8 42630.4 42943.9 43084.9 43175.0 7977.8 9615.6 9787.3 9795.9 9808.9 8069.3 11805.2 11997.4 12253.4 12255.0 12000.4 12061.3 12066.6 12072.1 12108.0 11952.5 11995.3 12005.6 12024.0 12026.9 11569.9 11608.0 11610.5 11619.5 11630.3 40267.8 40417.0 40444.4 40450.2 40631.2 3958.6 3972.1 3982.4 3985.9 4026.8 28269.0 28539.0 28579.9 29109.0 30215.2 3135.9 3250.8 3291.1 3318.9 3423.3 355417.0 357313.0 357715.0 357752.0 358196.0 52756.5 53356.2 53456.2 53471.2 53531.0 55519.4 55588.0 55604.7 55634.9 55644.4 13113.0 15061.9 15546.3 15547.6 15579.7 13356.5 13428.5 13445.5 13459.3 13492.3 9935.7 12098.6 12273.0 12284.1 12403.3 5222.5 5266.6 5273.4 5277.7 5294.0 941.1 968.8 973.4 982.7 983.2 28664.4 29588.0 29612.9 30238.4 30380.8 1049.8 1054.4 1067.6 1091.7 1093.7 11582.9 15320.7 15327.7 15523.8 15655.4 424776.0 424895.0 426937.0 427290.0 427561.9 57302.4 57419.0 57430.7 57468.7 57632.4 1269.7 1440.8 1478.7 1495.8 1499.1 18911.5 28158.3 28205.2 28730.9 28763.7 46002.3 46296.1 46334.3 46376.3 46445.0 450600.7 453985.0 453996.0 454381.0 455398.0 24457.8 24785.4 24857.6 25466.9 29072.8 31214.0 31397.8 38123.8 38598.8 38629.8 24705.2 26042.0 27275.0 28618.9 28641.7 27075.5 27478.3 27758.6 28128.3 28378.1 31345.5 31882.8 32540.8 32650.2 32704.9 27298.5 27340.7 27626.9 27996.4 28397.9 21903.7 22562.0 22907.7 22912.8 22930.7 04:47:28 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | [#2] jquery.html Cycles:180 Average:43799.38 Median:22664.50 stddev:125674.00 (554.5%) stddev-sans-first:125984.36 04:47:28 INFO - PROCESS | 2384 | Values: 430.0 437.9 438.4 441.1 451.6 20394.4 21265.8 22436.3 22466.3 22516.8 22391.9 23113.3 23634.3 23755.5 23844.9 6980.6 6986.2 7033.0 7109.8 7147.6 6641.3 6953.2 6957.4 6968.9 6972.9 6941.7 7596.8 7903.2 8097.4 8113.9 7805.6 7989.7 8034.4 8083.9 8113.7 7549.7 7813.3 7822.5 7824.1 7827.1 26711.9 27053.7 27160.6 27195.0 27525.9 2996.2 3190.0 3467.8 3493.6 3542.6 15753.8 16132.3 16582.2 16604.2 17280.9 2129.9 2135.1 2142.9 2163.7 2272.3 84455.8 105703.5 151407.0 173330.0 178044.1 20152.7 20396.2 20757.0 20934.6 21001.2 33059.3 33565.7 33760.0 33763.8 33824.5 10575.5 10790.6 10980.3 10996.4 11184.4 7617.7 9064.5 9099.1 9115.3 9294.8 8326.9 9160.3 9522.5 9706.2 9738.6 3128.6 3844.9 3862.9 3866.5 3887.5 698.9 823.6 1006.5 1053.3 1105.1 21268.9 22660.0 22669.0 22708.3 22760.6 1058.6 1066.3 1076.2 1085.6 1097.8 10942.6 11689.2 11700.8 11722.1 11724.5 709557.0 748762.0 758816.0 761911.0 780485.0 162919.2 167889.0 168523.0 171781.1 174529.0 1209.9 1216.1 1216.7 1245.9 1247.0 18549.8 18691.9 19099.1 19140.8 19322.4 63652.4 63686.7 64228.7 64611.0 66005.7 93548.0 94323.0 94621.0 96074.0 96987.0 11610.2 11765.2 11871.7 12229.6 12259.0 13337.9 13396.8 13428.5 13449.7 13472.4 11553.8 11608.7 11640.3 11674.1 11984.8 8988.4 10921.5 10955.5 10977.6 11003.6 11570.4 11724.7 11856.2 11937.0 12144.3 9861.5 11613.4 11876.3 12103.5 12176.5 9236.9 11652.5 11743.1 11806.2 11948.5 04:47:28 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | [#3] mootools.html Cycles:180 Average:6829.71 Median:2514.07 stddev:4258.92 (169.4%) stddev-sans-first:4252.74 04:47:28 INFO - PROCESS | 2384 | Values: 1600.3 1611.9 1641.9 1653.1 1668.5 10468.2 11159.7 11167.1 11172.5 11177.5 10853.1 11017.1 11306.2 11510.5 11629.2 2475.0 2513.9 2518.3 2520.5 2521.1 2431.4 2461.5 2511.2 2511.6 2516.5 2462.5 2474.0 2496.3 2506.1 2514.3 2436.5 2472.0 2482.6 2489.4 2499.6 2198.5 2376.8 2453.1 2484.3 2484.6 11299.0 11522.5 11531.2 11538.0 11541.8 5928.6 6111.5 6117.1 6136.2 6137.5 3533.6 3559.7 3615.2 3678.0 3727.7 1199.9 1206.7 1221.6 1223.6 1264.3 13906.7 14241.6 14619.4 15865.4 16041.4 4264.9 4322.6 4360.6 4483.4 4550.7 12113.6 12131.8 12181.3 12186.6 12200.8 6062.1 6065.2 6078.1 6079.0 6084.0 5415.1 5425.6 5468.7 5484.8 5553.6 3916.5 3986.3 4017.2 4057.4 4064.4 910.6 1062.4 1064.7 1073.5 1076.2 955.6 981.2 984.6 987.4 1012.1 6590.9 6627.2 6709.3 6853.5 6858.9 895.7 988.3 1022.4 1031.3 1059.7 3692.9 4216.4 4217.3 4308.2 4462.8 13782.2 14374.9 14377.3 14388.0 14391.5 13882.4 14100.7 14405.0 14413.9 14447.2 8975.9 9279.0 10420.8 10579.7 10634.8 6168.6 6738.1 6965.2 7095.8 7112.0 11881.6 11994.8 12013.7 12225.3 12265.6 12230.9 12289.7 12350.1 12438.8 12459.6 8434.9 8680.6 8803.6 8809.6 8815.4 10415.4 10418.4 10534.5 10560.2 10565.8 8908.4 8917.3 8971.0 8982.9 9014.5 6973.8 7424.7 8642.0 8805.9 8806.1 7801.5 8076.0 8166.1 8169.8 8221.9 5974.5 6074.9 6092.4 6100.5 6264.3 5095.4 5981.9 6215.0 6247.5 6284.4 04:47:28 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | [#4] prototype.html Cycles:180 Average:4446.85 Median:4831.63 stddev:1213.18 (25.1%) stddev-sans-first:1177.64 04:47:28 INFO - PROCESS | 2384 | Values: 384.3 387.4 393.1 396.4 400.7 4180.4 4475.7 4976.9 4992.5 5050.2 4774.7 4850.6 4957.8 4999.8 5082.8 4042.3 4176.5 4201.7 4206.8 4220.4 3695.9 4221.5 4222.4 4223.6 4247.3 4158.0 4228.9 4233.0 4234.9 4278.1 3654.7 4067.2 4151.0 4184.6 4227.4 3564.4 3834.4 3850.1 3854.0 3858.2 4438.6 4940.8 4974.7 4988.9 5012.3 4205.6 4320.3 4573.7 4804.2 4812.7 4921.3 5055.9 5257.7 5264.4 5276.7 4067.9 4182.9 4307.6 4310.8 4318.5 4932.4 4962.9 5085.8 5307.8 5322.1 3710.8 4351.3 4365.2 4400.5 4417.3 3855.2 4487.6 4521.9 4551.5 4566.9 4012.9 4516.9 4612.8 4806.4 4917.0 5252.6 5284.9 5334.3 5335.9 5348.9 1283.8 1514.8 1516.7 1521.0 1526.4 1012.6 1071.9 1139.6 1155.7 1177.9 4237.9 5009.6 5010.2 5047.8 5062.5 4952.7 4979.4 5026.8 5284.5 5462.2 3851.9 4605.4 4766.5 5008.8 5024.5 2598.0 2865.6 2874.8 2877.0 2915.2 5471.5 5704.7 5710.9 5720.7 5732.7 4931.8 5240.6 5273.6 5450.0 5626.3 5580.9 5650.9 5652.2 5678.9 5711.3 4683.0 5004.7 5316.3 5428.4 5565.1 5060.9 5205.5 5416.1 5422.2 5460.6 5222.6 5254.8 5437.6 5694.9 5728.8 4261.0 4290.7 4361.0 4364.5 4378.6 4543.0 4989.4 4994.1 5480.4 5513.3 4852.3 5133.3 5281.6 5348.4 5384.7 4601.8 5047.0 5149.1 5295.3 5313.7 4776.8 4901.8 5046.2 5143.3 5213.6 4803.8 4879.5 5047.1 5172.9 5272.5 4371.0 4681.5 4695.8 4790.7 5194.8 04:47:28 INFO - PROCESS | 2384 | 04:47:28 INFO - PROCESS | 2384 | [#5] yui.html Cycles:180 Average:20547.29 Median:16978.92 stddev:29026.66 (171.0%) stddev-sans-first:29070.07 04:47:28 INFO - PROCESS | 2384 | Values: 761.7 776.0 815.2 815.9 884.8 16078.0 16946.9 16975.0 16982.8 17004.6 15688.5 15690.5 15690.7 15843.4 15863.5 999.2 1020.0 1020.6 1020.9 1053.0 1036.8 1044.9 1045.5 1045.5 1046.3 1091.9 1093.6 1094.1 1095.3 1095.3 1077.7 1078.6 1079.7 1082.7 1083.7 806.6 809.1 809.4 810.3 814.0 16431.6 16881.2 17259.4 17313.7 17577.0 11887.6 14520.1 14599.7 14668.3 14691.0 11883.0 11923.9 12080.9 12106.8 12692.2 3848.3 3851.5 3879.5 3895.7 4026.2 87608.0 112788.0 113926.0 115282.0 117048.4 9747.7 11161.8 11168.6 11636.9 11745.8 23115.5 23464.6 23667.6 23913.6 23914.5 8034.3 8040.1 8175.7 8207.3 8449.6 4532.9 4547.0 4634.2 4667.4 4840.3 3548.6 3601.2 3603.9 3605.2 3606.7 573.2 659.7 684.7 694.0 698.3 708.9 738.2 755.2 766.5 769.4 10248.9 10755.7 10965.5 11157.9 11294.8 686.4 698.6 719.3 741.4 748.3 5111.9 5216.1 5227.4 5233.9 5236.9 87922.0 97528.0 103922.0 106754.5 109828.0 47609.0 77057.0 88206.6 88648.0 93129.0 58763.0 77308.0 79082.0 79810.6 79831.6 10710.6 10929.7 11208.5 11454.9 11605.3 46686.0 49408.8 49775.8 50317.5 51514.7 68426.3 69051.3 70980.0 71028.6 71382.3 10835.3 12929.9 13024.0 13174.9 13475.9 14292.9 14740.9 14765.0 15258.5 15388.8 12109.3 13911.2 14405.6 14552.7 15063.4 13620.8 14109.2 14120.8 14416.2 14734.3 13263.3 13853.2 14073.4 14078.4 14129.6 9440.9 10072.5 10135.4 10239.6 10430.5 8270.3 9182.8 9569.5 10390.6 11108.8 04:47:28 INFO - PROCESS | 2384 | -------- Summary: end -------- 04:47:28 INFO - PROCESS | 2384 | 04:47:29 INFO - TEST-INFO | 2384: exit 0 04:47:29 INFO - TEST-OK | dromaeo_css | took 1117632ms 04:47:29 INFO - TEST-START | kraken 04:47:29 INFO - operating with platform_type : mac_ 04:47:29 INFO - Initialising browser for kraken test... 04:47:29 INFO - TEST-INFO | started process 2387 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpMftaj0/profile http://localhost:60295/getInfo.html) 04:47:31 INFO - PROCESS | 2387 | __metrics Screen width/height:1600/1200 04:47:31 INFO - PROCESS | 2387 | colorDepth:24 04:47:31 INFO - PROCESS | 2387 | Browser inner width/height: 1024/689 04:47:31 INFO - PROCESS | 2387 | __metrics 04:47:31 INFO - PROCESS | 2387 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:47:31 INFO - PROCESS | 2387 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:47:32 INFO - TEST-INFO | 2387: exit 0 04:47:32 INFO - Browser initialized. 04:47:32 INFO - Running cycle 1/1 for kraken test... 04:47:32 INFO - TEST-INFO | started process 2389 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpMftaj0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:47:44 INFO - PROCESS | 2389 | RSS: Main: 165076992 04:47:44 INFO - PROCESS | 2389 | 04:47:46 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60295/tests/kraken/driver.html?testName=audio-beat-detection) 04:47:47 INFO - PROCESS | 2389 | RSS: Main: 217456640 04:47:47 INFO - PROCESS | 2389 | 04:47:50 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60295/tests/kraken/driver.html?testName=audio-dft) 04:47:50 INFO - PROCESS | 2389 | RSS: Main: 233619456 04:47:50 INFO - PROCESS | 2389 | 04:47:54 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60295/tests/kraken/driver.html?testName=audio-fft) 04:47:54 INFO - PROCESS | 2389 | RSS: Main: 328671232 04:47:54 INFO - PROCESS | 2389 | 04:47:56 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60295/tests/kraken/driver.html?testName=audio-oscillator) 04:47:57 INFO - PROCESS | 2389 | RSS: Main: 230977536 04:47:57 INFO - PROCESS | 2389 | 04:47:59 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60295/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:48:00 INFO - PROCESS | 2389 | RSS: Main: 232796160 04:48:00 INFO - PROCESS | 2389 | 04:48:02 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60295/tests/kraken/driver.html?testName=imaging-darkroom) 04:48:03 INFO - PROCESS | 2389 | RSS: Main: 272527360 04:48:03 INFO - PROCESS | 2389 | 04:48:06 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60295/tests/kraken/driver.html?testName=imaging-desaturate) 04:48:07 INFO - PROCESS | 2389 | RSS: Main: 272736256 04:48:07 INFO - PROCESS | 2389 | 04:48:10 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60295/tests/kraken/driver.html?testName=json-parse-financial) 04:48:10 INFO - PROCESS | 2389 | RSS: Main: 271114240 04:48:10 INFO - PROCESS | 2389 | 04:48:12 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60295/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:48:12 INFO - PROCESS | 2389 | RSS: Main: 247836672 04:48:12 INFO - PROCESS | 2389 | 04:48:14 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:48:15 INFO - PROCESS | 2389 | RSS: Main: 251121664 04:48:15 INFO - PROCESS | 2389 | 04:48:17 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:48:18 INFO - PROCESS | 2389 | RSS: Main: 258961408 04:48:18 INFO - PROCESS | 2389 | 04:48:21 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:48:21 INFO - PROCESS | 2389 | RSS: Main: 273842176 04:48:21 INFO - PROCESS | 2389 | 04:48:24 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:48:25 INFO - PROCESS | 2389 | RSS: Main: 267522048 04:48:25 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60295/tests/kraken/driver.html?testName=ai-astar) 04:48:27 INFO - PROCESS | 2389 | RSS: Main: 312242176 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | __start_tp_report 04:48:27 INFO - PROCESS | 2389 | _x_x_mozilla_page_load 04:48:27 INFO - PROCESS | 2389 | _x_x_mozilla_page_load_details 04:48:27 INFO - PROCESS | 2389 | |i|pagename|runs| 04:48:27 INFO - PROCESS | 2389 | |0;ai-astar;103;115;114;114;115;114;114;113;114;114 04:48:27 INFO - PROCESS | 2389 | |1;audio-beat-detection;131;109;107;141;111;104;105;107;123;106 04:48:27 INFO - PROCESS | 2389 | |2;audio-dft;186;186;181;184;184;184;184;186;186;185 04:48:27 INFO - PROCESS | 2389 | |3;audio-fft;83;58;59;93;77;84;83;78;73;60 04:48:27 INFO - PROCESS | 2389 | |4;audio-oscillator;88;68;86;69;69;69;82;68;69;82 04:48:27 INFO - PROCESS | 2389 | |5;imaging-gaussian-blur;86;86;86;86;86;86;86;87;87;86 04:48:27 INFO - PROCESS | 2389 | |6;imaging-darkroom;93;92;94;105;149;108;94;93;93;93 04:48:27 INFO - PROCESS | 2389 | |7;imaging-desaturate;86;84;84;85;85;84;84;86;83;85 04:48:27 INFO - PROCESS | 2389 | |8;json-parse-financial;61;58;59;58;61;61;61;61;63;64 04:48:27 INFO - PROCESS | 2389 | |9;json-stringify-tinderbox;55;60;83;97;81;60;60;60;60;62 04:48:27 INFO - PROCESS | 2389 | |10;stanford-crypto-aes;80;78;84;83;84;84;79;80;87;88 04:48:27 INFO - PROCESS | 2389 | |11;stanford-crypto-ccm;148;136;142;130;130;133;135;149;203;144 04:48:27 INFO - PROCESS | 2389 | |12;stanford-crypto-pbkdf2;159;161;149;162;139;207;218;170;160;159 04:48:27 INFO - PROCESS | 2389 | |13;stanford-crypto-sha256-iterative;66;65;67;64;64;63;66;63;64;66 04:48:27 INFO - PROCESS | 2389 | __end_tp_report 04:48:27 INFO - PROCESS | 2389 | __start_cc_report 04:48:27 INFO - PROCESS | 2389 | _x_x_mozilla_cycle_collect,2062 04:48:27 INFO - PROCESS | 2389 | __end_cc_report 04:48:27 INFO - PROCESS | 2389 | __startTimestamp1496058507348__endTimestamp 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | ------- Summary: start ------- 04:48:27 INFO - PROCESS | 2389 | Number of tests: 14 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:3.56 (3.1%) stddev-sans-first:0.60 04:48:27 INFO - PROCESS | 2389 | Values: 103.0 115.0 114.0 114.0 115.0 114.0 114.0 113.0 114.0 114.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#1] audio-beat-detection Cycles:10 Average:114.40 Median:110.00 stddev:12.80 (11.6%) stddev-sans-first:12.08 04:48:27 INFO - PROCESS | 2389 | Values: 131.0 109.0 107.0 141.0 111.0 104.0 105.0 107.0 123.0 106.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.50 stddev:1.58 (0.9%) stddev-sans-first:1.59 04:48:27 INFO - PROCESS | 2389 | Values: 186.0 186.0 181.0 184.0 184.0 184.0 184.0 186.0 186.0 185.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#3] audio-fft Cycles:10 Average:74.80 Median:80.50 stddev:12.11 (15.0%) stddev-sans-first:12.47 04:48:27 INFO - PROCESS | 2389 | Values: 83.0 58.0 59.0 93.0 77.0 84.0 83.0 78.0 73.0 60.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:75.50 stddev:8.37 (11.1%) stddev-sans-first:7.43 04:48:27 INFO - PROCESS | 2389 | Values: 88.0 68.0 86.0 69.0 69.0 69.0 82.0 68.0 69.0 82.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:86.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 04:48:27 INFO - PROCESS | 2389 | Values: 86.0 86.0 86.0 86.0 86.0 86.0 86.0 87.0 87.0 86.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:93.00 stddev:17.65 (19.0%) stddev-sans-first:18.45 04:48:27 INFO - PROCESS | 2389 | Values: 93.0 92.0 94.0 105.0 149.0 108.0 94.0 93.0 93.0 93.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:0.88 04:48:27 INFO - PROCESS | 2389 | Values: 86.0 84.0 84.0 85.0 85.0 84.0 84.0 86.0 83.0 85.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:61.00 stddev:1.95 (3.2%) stddev-sans-first:2.06 04:48:27 INFO - PROCESS | 2389 | Values: 61.0 58.0 59.0 58.0 61.0 61.0 61.0 61.0 63.0 64.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:61.00 stddev:13.98 (22.9%) stddev-sans-first:14.04 04:48:27 INFO - PROCESS | 2389 | Values: 55.0 60.0 83.0 97.0 81.0 60.0 60.0 60.0 60.0 62.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:84.00 stddev:3.37 (4.0%) stddev-sans-first:3.43 04:48:27 INFO - PROCESS | 2389 | Values: 80.0 78.0 84.0 83.0 84.0 84.0 79.0 80.0 87.0 88.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:143.00 stddev:21.54 (15.1%) stddev-sans-first:22.81 04:48:27 INFO - PROCESS | 2389 | Values: 148.0 136.0 142.0 130.0 130.0 133.0 135.0 149.0 203.0 144.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:161.50 stddev:24.80 (15.4%) stddev-sans-first:26.07 04:48:27 INFO - PROCESS | 2389 | Values: 159.0 161.0 149.0 162.0 139.0 207.0 218.0 170.0 160.0 159.0 04:48:27 INFO - PROCESS | 2389 | 04:48:27 INFO - PROCESS | 2389 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.50 stddev:1.40 (2.1%) stddev-sans-first:1.41 04:48:27 INFO - PROCESS | 2389 | Values: 66.0 65.0 67.0 64.0 64.0 63.0 66.0 63.0 64.0 66.0 04:48:27 INFO - PROCESS | 2389 | -------- Summary: end -------- 04:48:27 INFO - PROCESS | 2389 | 04:48:28 INFO - TEST-INFO | 2389: exit 0 04:48:28 INFO - TEST-OK | kraken | took 58899ms 04:48:28 INFO - SUITE-END | took 1176s 04:48:28 INFO - Completed test suite (00:19:36) 04:48:28 INFO - javascript benchmark 04:48:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7811.563271589568, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2991.326951435927, 3475.2701932594155, 3495.685388315053, 3540.6990405815504, 3543.901390568924, 6011.849703757408, 6070.089486577016, 6082.635041897478, 6091.116788065733, 6144.69276536173, 5996.0, 6085.847853803657, 6089.6955152242435, 6095.329513753483, 6109.327973922865, 5384.111621582434, 5442.591805614575, 5445.1832225166145, 5448.101063324547, 5543.279373681436, 5346.946530534703, 5350.170723537858, 5381.569474442055, 5445.319335083114, 5584.4136365681625, 5517.724113794315, 5527.447255274481, 5533.501984821365, 5574.1917421973785, 5597.216389705444, 5498.1752737089355, 5534.335879694422, 5535.778568857261, 5540.722963851791, 5550.389457159709, 5352.785888564453, 5472.206530053159, 5479.945200547983, 5527.917081243784, 5532.972335138298, 4972.801087956514, 5893.941060589425, 5928.614640048383, 5989.670568118751, 6080.270367555878, 6357.618542887395, 6371.745130194787, 6376.744930202786, 6377.8405539861415, 6379.043143528462, 6317.4946004319545, 6428.421442070189, 6456.48348132153, 6458.709358078898, 6485.448736857373, 5967.910481342783, 5989.610675306091, 6089.086637004485, 6216.2851272103835, 6224.097505861625, 5368.194770784414, 6388.073729309224, 6410.326915673881, 6483.384078512629, 6603.933960660428, 6282.685865706696, 6306.400891915261, 6325.588836725598, 6335.936640633535, 6336.239651241881, 6380.3619638035825, 6443.613383197023, 6450.8064758057335, 6454.031895215661, 6473.06140609609, 6262.935300998841, 6313.747450102036, 6351.587146835441, 6365.267994180728, 6372.044193371032, 6301.968490157612, 6313.873722525524, 6365.649889256136, 6372.968135159294, 6383.489320854321, 5192.376914770176, 5238.188080847474, 5247.239150323258, 5257.08001099806, 5261.736913154327, 4593.242115050979, 4676.649251306166, 4700.08348372071, 4700.341952126705, 4724.196886529298, 6059.909101363483, 6073.210482637229, 6096.542759293071, 6099.146119543268, 6103.42017508336, 6319.115323854664, 6333.556651034383, 6348.365163483707, 6348.428641422294, 6349.777757778455, 6096.69516524172, 6110.266767987781, 6181.907271390933, 6184.319724830265, 6187.412195841388, 5699.202111704365, 5706.4864162225595, 5711.600187986801, 5720.942790572041, 5723.456271654186, 6645.259253727551, 6665.166854143232, 6676.26561078281, 6695.698693558801, 6702.430293425114, 6257.2491301044165, 6294.842628934313, 6365.9681701593045, 6391.7123729433215, 6451.483881289391, 5764.827055188351, 6677.465802735866, 6720.6975686093265, 6720.764773232913, 6721.462283017249, 6196.690165491797, 6365.267994180544, 6376.3623637635865, 6395.16862807832, 6398.808035758934, 6253.280872699698, 6295.370462953668, 6342.936570634419, 6456.741730330734, 6471.741130354922, 5256.316678831728, 5306.708131052675, 6272.560920735505, 6281.905771413341, 6345.333739957238, 6346.9682651586445, 6346.9682651586445, 6347.714352854225, 6351.68241587928, 6356.872862542816, 6109.877802444015, 6166.136740856194, 6277.497800176066, 6291.339409362135, 6372.55392122547, 6246.281677607133, 6288.465480434214, 6317.810465686037, 6326.2092238470195, 6329.525285603506, 6331.0, 6332.113504109337, 6344.524160687874, 6352.460040896575, 6360.141380913517], "value": 5927.628484621425, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.9224873637004, 829.005193767479, 898.5372533145431, 913.7121806630915, 915.354674954157, 41324.793376033114, 42036.57963420361, 42125.73622791317, 42153.94615134623, 42158.57841421585, 34661.826690866576, 42630.36054459186, 42943.85280735966, 43084.92287692809, 43175.0, 7977.760667179994, 9615.567299471522, 9787.265955053364, 9795.902040979607, 9808.852867206997, 8069.314108300801, 11805.232659877123, 11997.40012999351, 12253.387330633477, 12255.0, 12000.399980000964, 12061.27632342058, 12066.57766978156, 12072.094592905523, 12108.0, 11952.521899124025, 11995.340256285901, 12005.639830805003, 12023.977961873252, 12026.917577418031, 11569.884301156964, 11608.0, 11610.477528511237, 11619.477123529461, 11630.30218186903, 40267.798661006804, 40417.0, 40444.393334100016, 40450.19099617991, 40631.18737625261, 3958.6437220650278, 3972.145988612462, 3982.38273067675, 3985.900352491182, 4026.838926442939, 28269.010584629646, 28539.0, 28579.857100714362, 29109.0, 30215.244618884484, 3135.9372812543847, 3250.777846919726, 3291.078498020558, 3318.883839065645, 3423.2982238641266, 355417.0, 357313.0, 357715.0, 357752.0, 358195.9999999974, 52756.47243527592, 53356.19965700518, 53456.19815702768, 53471.19793203027, 53531.0, 55519.44480555224, 55588.0, 55604.72197639067, 55634.88730225373, 55644.443555564736, 13113.0, 15061.924690376478, 15546.300416481285, 15547.611309717122, 15579.688406231811, 13356.532521361705, 13428.462861485626, 13445.46218151263, 13459.327033648278, 13492.325383730971, 9935.701928942144, 12098.576549820715, 12273.018158547295, 12284.078694097978, 12403.25580465175, 5222.529972302511, 5266.552343050807, 5273.3935597406025, 5277.683338999673, 5293.973530132402, 941.1388579449726, 968.7917097824002, 973.3673112476947, 982.6708052802248, 983.1544871410581, 28664.426711465654, 29587.964421245153, 29612.851935740182, 30238.395232095674, 30380.848095759822, 1049.8372752223418, 1054.3884546962743, 1067.5889782433665, 1091.7434402915467, 1093.7429704019548, 11582.88417115835, 15320.693586128215, 15327.693446131016, 15523.767143492856, 15655.373785048698, 424776.0, 424895.0, 426937.0, 427290.0, 427561.86219069327, 57302.426975730545, 57419.0, 57430.7128464355, 57468.71265643645, 57632.42367576271, 1269.7206614544784, 1440.819897512811, 1478.6820833520844, 1495.8055452791068, 1499.14548707238, 18911.497297664508, 28158.2960425991, 28205.15384538467, 28730.856345718974, 28763.71236287694, 46002.30996534988, 46296.07407851891, 46334.30498542592, 46376.30435543537, 46444.99999999865, 450600.7469962629, 453985.0, 453996.0, 454381.0, 455398.0, 24457.75542244555, 24785.380365491008, 24857.627135593342, 25466.872665636554, 29072.83708651715, 31214.0, 31397.8430107848, 38123.83557299657, 38598.80700596479, 38629.806850965564, 24705.236360005416, 26042.0, 27275.045373411835, 28618.85690571534, 28641.713582863904, 27075.458490830468, 27478.313042173308, 27758.583621245292, 28128.29679258035, 28378.148655540368, 31345.529817052306, 31882.840585796923, 32540.83729581337, 32650.183745406553, 32704.855330063332, 27298.454030919667, 27340.726592734612, 27626.89492420361, 27996.44007119887, 28397.858010709813, 21903.67144492866, 22562.0, 22907.656385153903, 22912.770872291065, 22930.656040159745], "value": 18755.146192219094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.9573534179614, 437.89928316487186, 438.3578058144817, 441.13097198518915, 451.58905396089546, 20394.388168354955, 21265.787342126594, 22436.326910192678, 22466.32601021968, 22516.77483225167, 22391.888040559774, 23113.30660080196, 23634.290971270846, 23755.5248895022, 23844.926978285974, 6980.616066116361, 6986.231514533397, 7033.0, 7109.751158709446, 7147.57114573126, 6641.335866413368, 6953.165620125592, 6957.373836354727, 6968.930310696879, 6972.860542789142, 6941.6876240569045, 7596.848063038736, 7903.249191326815, 8097.392695547828, 8113.918860811375, 7805.5706936118495, 7989.680412783482, 8034.437589368747, 8083.8787418188185, 8113.716019939303, 7549.660265288076, 7813.335866451358, 7822.4524283299625, 7824.139344672081, 7827.1390147083775, 26711.866440667865, 27053.729462705316, 27160.592591111148, 27195.048173314037, 27525.862370688217, 2996.1910284223136, 3189.997314314697, 3467.809270490122, 3493.580770307554, 3542.6103128655827, 15753.842461575352, 16132.274047667883, 16582.17089145538, 16604.169791510496, 17280.913595432066, 2129.936101916945, 2135.124598914453, 2142.946426339354, 2163.664631982045, 2272.2728726807263, 84455.76606697404, 105703.47148264287, 151407.0, 173330.0, 178044.1097794503, 20152.69770953437, 20396.184152634025, 20757.0, 20934.58130837375, 21001.159953601684, 33059.3388132236, 33565.664343356744, 33760.0, 33763.83118084394, 33824.49263261053, 10575.524101415454, 10790.622328218475, 10980.341179529254, 10996.395198264012, 11184.44077796107, 7617.6572054257695, 9064.501452420049, 9099.134677391252, 9115.316351273548, 9294.767630809149, 8326.875871757356, 9160.312976526788, 9522.476263805418, 9706.185206290238, 9738.610455581838, 3128.6390420166845, 3844.8654297099465, 3862.8841134766003, 3866.478025466582, 3887.5140607424073, 698.9231184569693, 823.6170180865926, 1006.5269323417982, 1053.2679787547581, 1105.1269497097203, 21268.93655317228, 22660.0, 22669.0, 22708.318750437513, 22760.610135935818, 1058.6400623787795, 1066.3122286125406, 1076.160594736107, 1085.6124075665175, 1097.7924283288319, 10942.562297508011, 11689.240199387013, 11700.824487632692, 11722.062235021178, 11724.531018759324, 709557.0, 748762.0, 758816.0, 761911.0, 780485.0, 162919.18540407222, 167889.0, 168523.0, 171781.14109429374, 174529.0, 1209.8850609192114, 1216.0514798457218, 1216.7079900823915, 1245.9190152640049, 1246.9961680846825, 18549.814501855348, 18691.90654046721, 19099.140538675514, 19140.8085919139, 19322.420327390202, 63652.408689781274, 63686.681566593725, 64228.67885660542, 64611.03083453846, 66005.66997164984, 93548.0, 94323.0, 94621.0, 96074.0, 96987.0, 11610.24533405326, 11765.235259708092, 11871.703207419883, 12229.571964981184, 12259.019278457523, 13337.933310333774, 13396.799048014092, 13428.530001449903, 13449.731005380034, 13472.393742281816, 11553.82669259945, 11608.709782255512, 11640.301581905112, 11674.124440667154, 11984.76030479403, 8988.415752976036, 10921.453927303444, 10955.50700218476, 10977.615783447542, 11003.61487347939, 11570.363630000431, 11724.70688232801, 11856.229345092886, 11937.0, 12144.33206173669, 9861.457619830979, 11613.3612651305, 11876.287422754662, 12103.455344509342, 12176.512939482322, 9236.907630923604, 11652.475638596454, 11743.11926605491, 11806.173567850168, 11948.522059117538], "value": 12035.619654545468, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1600.3198640577766, 1611.9355225790969, 1641.8850680452374, 1653.082539190749, 1668.549491637258, 10468.214883883711, 11159.721006974809, 11167.050800681951, 11172.497237624306, 11177.49701263443, 10853.131749460044, 11017.11863050956, 11306.208565400426, 11510.539578416874, 11629.185956982994, 2475.0471068638567, 2513.861737604431, 2518.3326418499114, 2520.5085008423366, 2521.1050077222503, 2431.3799981004818, 2461.544614246361, 2511.196417146514, 2511.573032584465, 2516.484120755241, 2462.495188486365, 2473.9752602474014, 2496.251124662594, 2506.1103308325555, 2514.270861450186, 2436.50051739393, 2471.9629205561932, 2482.553140434721, 2489.4274316907213, 2499.6125600531855, 2198.483356411242, 2376.7997161433536, 2453.092355828355, 2484.2795589279085, 2484.571371461399, 11299.0, 11522.539098436053, 11531.192816502848, 11538.019268362199, 11541.769164616744, 5928.555358348141, 6111.480524155452, 6117.113018612321, 6136.202293701835, 6137.539684523678, 3533.593636731783, 3559.661832125944, 3615.1685112424025, 3678.0253232893306, 3727.7204209684382, 1199.8920097191294, 1206.6681662542783, 1221.5663439479035, 1223.6451429085555, 1264.2856785915928, 13906.721865562733, 14241.572752817432, 14619.415223391052, 15865.444709435229, 16041.35834566616, 4264.850730224427, 4322.5677432256525, 4360.585744354345, 4483.3947417098925, 4550.658700597469, 12113.636590902288, 12131.818022729665, 12181.269123852597, 12186.57346992851, 12200.816987745191, 6062.120992456069, 6065.2418447694035, 6078.057901025348, 6079.0273556230795, 6083.969580152071, 5415.0523658359625, 5425.565954723613, 5468.699221542774, 5484.808031718871, 5553.611247212737, 3916.4908561886773, 3986.3023970804993, 4017.216642754635, 4057.411675307064, 4064.4106604542176, 910.649399981012, 1062.4103622489592, 1064.7444613292755, 1073.5276478349501, 1076.2304951959386, 955.6429869585066, 981.2493442516338, 984.6356847966298, 987.4223579206101, 1012.0891197921782, 6590.934090659032, 6627.204735431779, 6709.261981182066, 6853.485988550879, 6858.897116543256, 895.6596493332622, 988.2983082011853, 1022.3712416863724, 1031.2626472072354, 1059.6820953713855, 3692.9076773080888, 4216.388623649615, 4217.34631132175, 4308.202982448194, 4462.754548499864, 13782.173069615856, 14374.85625143756, 14377.281135943162, 14388.0, 14391.496297629532, 13882.444702212, 14100.717985640435, 14404.983302337681, 14413.927930360282, 14447.205403702897, 8975.910240897638, 9278.999999999865, 10420.843687344695, 10579.682609521727, 10634.787304253872, 6168.568200226121, 6738.090357801634, 6965.164180298397, 7095.751648692271, 7112.039874617068, 11881.5841445549, 11994.76010479803, 12013.699657508632, 12225.327606981346, 12265.57070502528, 12230.938845305716, 12289.69275768113, 12350.07374446902, 12438.751224975631, 12459.563915262923, 8434.915650843413, 8680.565971701264, 8803.60383782744, 8809.559522024001, 8815.382923195315, 10415.375077495375, 10418.374897506174, 10534.473276335999, 10560.155187584822, 10565.841512377474, 8908.420952638317, 8917.331200159884, 8971.0, 8982.86525702128, 9014.459132452073, 6973.755918542826, 7424.665890034853, 8642.0, 8805.867911981453, 8806.075362086889, 7801.531908085533, 8076.030876294647, 8166.060902996232, 8169.795755105933, 8221.876671849808, 5974.522038236843, 6074.908876366946, 6092.355064132748, 6100.54245931548, 6264.2796078451565, 5095.388553373619, 5981.850453738692, 6215.005599104122, 6247.531435142291, 6284.402981716729], "value": 5226.871610123397, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.3446922996292, 387.3821255098119, 393.0998014546688, 396.38956007748067, 400.68746377825306, 4180.393842892787, 4475.731456112631, 4976.929960058586, 4992.525710057548, 5050.242463630453, 4774.713517188962, 4850.563449289563, 4957.801687932488, 4999.8000079996755, 5082.822101226458, 4042.272390969624, 4176.540580536139, 4201.663866890642, 4206.789660516977, 4220.430241917348, 3695.90760230995, 4221.535631080594, 4222.429971953793, 4223.577642235768, 4247.299195632732, 4157.964685779383, 4228.915421691564, 4233.0, 4234.87295381139, 4278.080212754257, 3654.689351405134, 4067.1865626874596, 4151.045259590311, 4184.581541845791, 4227.408162857202, 3564.4296912494137, 3834.4440056191975, 3850.1337199130253, 3853.98073009636, 3858.2189454334907, 4438.556144385536, 4940.802367905279, 4974.676646017997, 4988.850334489971, 5012.270766585559, 4205.558416366299, 4320.336670381926, 4573.679842411033, 4804.159272127364, 4812.687175333592, 4921.311016457699, 5055.873603159914, 5257.658252213632, 5264.394594621629, 5276.704633484446, 4067.9389809152885, 4182.853600124012, 4307.569243075667, 4310.82756689732, 4318.546552611993, 4932.432770231433, 4962.925556116661, 5085.829619711801, 5307.787688492455, 5322.119058440407, 3710.7735358785053, 4351.26028575143, 4365.236083685342, 4400.481833767345, 4417.271150260235, 3855.2265957659793, 4487.586410280751, 4521.892136426531, 4551.4765801932535, 4566.931496027562, 4012.9197416051516, 4516.932246016312, 4612.76936153191, 4806.351142595775, 4917.0, 5252.612065217913, 5284.894302113936, 5334.333208348957, 5335.866603334947, 5348.9465105348445, 1283.7560863435917, 1514.8487149766036, 1516.673915108257, 1521.0, 1526.4275896538797, 1012.6455740490769, 1071.919606029551, 1139.5669645534645, 1155.7341811383428, 1177.9469923853446, 4237.91524169521, 5009.574186194214, 5010.22341537055, 5047.753749581474, 5062.493750624908, 4952.678075925125, 4979.427365852901, 5026.81869760606, 5284.497972692665, 5462.2079798429, 3851.9037024074064, 4605.424321959755, 4766.475687674353, 5008.84973450797, 5024.522670346311, 2597.9610305845426, 2865.5958580295924, 2874.841883696375, 2877.0, 2915.2128925190505, 5471.4528547145765, 5704.691279058136, 5710.914336284959, 5720.656760594295, 5732.6847023413275, 4931.778069986859, 5240.580753539708, 5273.604479664041, 5450.0, 5626.2685850839125, 5580.86047848807, 5650.85872853182, 5652.236948012048, 5678.914816277759, 5711.314642242957, 4682.976585117189, 5004.724740139327, 5316.2786049764545, 5428.402875683592, 5565.053940830149, 5060.949390506048, 5205.531502164823, 5416.133418652997, 5422.15956526739, 5460.590455715757, 5222.55608273301, 5254.763535640829, 5437.619366644297, 5694.857628559319, 5728.828135155952, 4261.0, 4290.742555446684, 4360.956390436055, 4364.519902810624, 4378.584034516716, 4542.954570454253, 4989.401271847401, 4994.076095922266, 5480.424555421623, 5513.26332205354, 4852.296417019583, 5133.307003554622, 5281.630285879952, 5348.384935732441, 5384.6769193848495, 4601.792919318706, 5046.9747651261505, 5149.094834911107, 5295.299397307324, 5313.681179129265, 4776.781920610222, 4901.848065704486, 5046.1673823818655, 5143.331366922276, 5213.635045546927, 4803.759812009455, 4879.536444037811, 5047.1419858623385, 5172.922406163986, 5272.499112584298, 4370.956290437055, 4681.461631912373, 4695.812167513398, 4790.7365094920115, 5194.844154675366], "value": 4096.992391891815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.7219714804098, 776.0105865022097, 815.1929589706191, 815.8572249856273, 884.8274586455642, 16078.0, 16946.91526542367, 16975.0, 16982.830171698264, 17004.574885627833, 15688.529344119695, 15690.450834220805, 15690.686186276267, 15843.445479408223, 15863.524094277162, 999.1956475037592, 1019.9541020654088, 1020.6070662794804, 1020.9489525523732, 1052.9631462898801, 1036.813373592753, 1044.858944042556, 1045.4668119259195, 1045.4720366215051, 1046.2989796836139, 1091.8525998990115, 1093.5789720957448, 1094.0973696700214, 1095.2880627592049, 1095.2935356694927, 1077.7144056824948, 1078.6170909327154, 1079.7246702090995, 1082.680609220278, 1083.696564961808, 806.6410447350931, 809.0695699945052, 809.3686924199076, 810.3314765318636, 813.9918600814035, 16431.589210269718, 16881.15594220284, 17259.395921142826, 17313.653726925517, 17577.0, 11887.643370698894, 14520.056196347203, 14599.70800583993, 14668.339924703414, 14691.045082069739, 11883.0, 11923.94038029813, 12080.939595302056, 12106.757864842742, 12692.17500862441, 3848.2688289224957, 3851.499305090348, 3879.4568760373572, 3895.7467764595212, 4026.1545075534173, 87608.0, 112788.0, 113926.0, 115282.0, 117048.41475792651, 9747.65883194099, 11161.776764464828, 11168.56770652059, 11636.88363116375, 11745.823812642817, 23115.537689246456, 23464.64803027956, 23667.644985325234, 23913.641295380585, 23914.52170956606, 8034.317083047889, 8040.115587285394, 8175.67297308101, 8207.343412526983, 8449.619767110495, 4532.9320060199125, 4546.977265113654, 4634.235351167087, 4667.416572928384, 4840.273958906122, 3548.6451354864307, 3601.225736466672, 3603.9099022524647, 3605.2248766515327, 3606.747527673038, 573.237593999979, 659.650385295794, 684.7397988764237, 694.035290945586, 698.2947223304499, 708.9042979197845, 738.2064280898056, 755.2108047090803, 766.4941138848334, 769.4114002787871, 10248.948755256177, 10755.677329679966, 10965.50655220517, 11157.94421027906, 11294.774104518028, 686.3788271614197, 698.6017969757189, 719.251977942944, 741.3513175971025, 748.3339827553482, 5111.87220319495, 5216.139337009351, 5227.372715274191, 5233.947660523422, 5236.92144617831, 87922.0, 97528.0, 103922.0, 106754.46622766992, 109828.0, 47609.0, 77057.0, 88206.55896720475, 88648.0, 93129.0, 58763.0, 77308.0, 79082.0, 79810.60094699489, 79831.60084199658, 10710.64365839514, 10929.726756831144, 11208.495617697386, 11454.942725286654, 11605.30368177912, 46686.0, 49408.75295623499, 49775.75112124416, 50317.49682503128, 51514.74242628763, 68426.31573684199, 69051.30948690449, 70980.0, 71028.64485677745, 71382.28617713756, 10835.349879007284, 12929.870701293243, 13024.0, 13174.934125329311, 13475.865241347461, 14292.928535357256, 14740.852591473948, 14765.0, 15258.465953691568, 15388.769168462706, 12109.273443593413, 13911.165330080228, 14405.639859003608, 14552.708945821236, 15063.397464101314, 13620.795688064885, 14109.153450792985, 14120.78818817698, 14416.207108609127, 14734.26328683583, 13263.270520121487, 13853.205086824495, 14073.437062517385, 14078.436862525385, 14129.576112716635, 9440.905590944003, 10072.496375181065, 10135.391876487434, 10239.641612543524, 10430.478476076014, 8270.338372930271, 9182.770430739285, 9569.473678947725, 10390.58437662485, 11108.778244351], "value": 7928.811093507211, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1423.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 109.0, 107.0, 141.0, 111.0, 104.0, 105.0, 107.0, 123.0, 106.0], "value": 114.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 181.0, 184.0, 184.0, 184.0, 184.0, 186.0, 186.0, 185.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 93.0, 77.0, 84.0, 83.0, 78.0, 73.0, 60.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 86.0, 69.0, 69.0, 69.0, 82.0, 68.0, 69.0, 82.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 86.0, 86.0, 86.0, 86.0, 87.0, 87.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 105.0, 149.0, 108.0, 94.0, 93.0, 93.0, 93.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 85.0, 85.0, 84.0, 84.0, 86.0, 83.0, 85.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 59.0, 58.0, 61.0, 61.0, 61.0, 61.0, 63.0, 64.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 60.0, 83.0, 97.0, 81.0, 60.0, 60.0, 60.0, 60.0, 62.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 84.0, 83.0, 84.0, 84.0, 79.0, 80.0, 87.0, 88.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 136.0, 142.0, 130.0, 130.0, 133.0, 135.0, 149.0, 203.0, 144.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 149.0, 162.0, 139.0, 207.0, 218.0, 170.0, 160.0, 159.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 64.0, 64.0, 63.0, 66.0, 63.0, 64.0, 66.0], "value": 64.8, "unit": "score"}]}]} 04:48:28 INFO - Return code: 0 04:48:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:48:28 INFO - # TBPL SUCCESS # 04:48:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:28 INFO - 2017-05-29 04:48:28.672 screenresolution[2393:80332] starting screenresolution argv=screenresolution get 04:48:28 INFO - 2017-05-29 04:48:28.675 screenresolution[2393:80332] Display 0: 1600x1200x32@60 04:48:28 INFO - 2017-05-29 04:48:28.682 screenresolution[2394:80335] starting screenresolution argv=screenresolution list 04:48:28 INFO - Available Modes on Display 0 04:48:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:28 INFO - Intel Iris: 04:48:28 INFO - Chipset Model: Intel Iris 04:48:28 INFO - Type: GPU 04:48:28 INFO - Bus: Built-In 04:48:28 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:28 INFO - Vendor: Intel (0x8086) 04:48:28 INFO - Device ID: 0x0a2e 04:48:28 INFO - Revision ID: 0x0009 04:48:28 INFO - Displays: 04:48:28 INFO - Display: 04:48:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:28 INFO - Display Serial Number: 1600x1200 60 04:48:28 INFO - Main Display: Yes 04:48:28 INFO - Mirror: Off 04:48:28 INFO - Online: Yes 04:48:28 INFO - Rotation: Supported 04:48:28 INFO - Return code: 0 04:48:28 INFO - Running post-action listener: _resource_record_post_action 04:48:28 INFO - [mozharness: 2017-05-29 11:48:28.919755Z] Finished run-tests step (success) 04:48:28 INFO - Running post-run listener: _resource_record_post_run 04:48:28 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 143360; Write bytes: 422803456; Read time: 4; Write time: 1368 04:48:28 INFO - TinderboxPrint: CPU usage
54.1% 04:48:28 INFO - TinderboxPrint: I/O read bytes / time
143,360 / 4 04:48:28 INFO - TinderboxPrint: I/O write bytes / time
422,803,456 / 1,368 04:48:28 INFO - TinderboxPrint: CPU idle
2,190.5 (45.9%) 04:48:28 INFO - TinderboxPrint: CPU system
957.7 (20.1%) 04:48:28 INFO - TinderboxPrint: CPU user
1,621.6 (34.0%) 04:48:28 INFO - TinderboxPrint: Swap in / out
121,733,120 / 0 04:48:28 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 110717440; Write bytes: 103366656; Read time: 9914; Write time: 224 04:48:28 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 143360; Write bytes: 299644928; Read time: 4; Write time: 1086 04:48:29 INFO - Running post-run listener: _upload_blobber_files 04:48:29 INFO - Blob upload gear active. 04:48:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:48:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:48:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:48:29 INFO - (blobuploader) - INFO - Open directory for files ... 04:48:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:48:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:48:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:32 INFO - (blobuploader) - INFO - Done attempting. 04:48:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:48:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:48:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:32 INFO - (blobuploader) - INFO - Done attempting. 04:48:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:48:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:48:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:33 INFO - (blobuploader) - INFO - Done attempting. 04:48:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:48:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:48:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:35 INFO - (blobuploader) - INFO - Done attempting. 04:48:35 INFO - (blobuploader) - INFO - Iteration through files over. 04:48:35 INFO - Return code: 0 04:48:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:48:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:48:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/03c21d1217cf053929a43595dcb1092fb80fa36a54f455270b6c63e46a2e97d7743145277c63462128e2c07938b6160e207e00a90cc1f3f63c51e2080d727cab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa22b5bc7c289f5f6a6ab7aa38025725835c096e8ffdeb39f71e61a1d551b8070e217197b8673cae5ea265a94a468a0d7a5baf58287d8412d77bbb1292b72305", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/590e49919a21e38d522d8e6a43ad57987fc864347aaae997df383a5a41aa6897f28db7dceb351740c6fa76bf462e715a9919c69fe9ce1eeaccdfaf758df28257", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df74ffac092313587052dcf6643967bfa5858d776c357b27c72025d2ef6e339fc348cfb3203a58f1ac821442c0f473167460eed2ca7cae163ee5be156f8ef14a"} 04:48:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:48:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:48:35 INFO - Contents: 04:48:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/03c21d1217cf053929a43595dcb1092fb80fa36a54f455270b6c63e46a2e97d7743145277c63462128e2c07938b6160e207e00a90cc1f3f63c51e2080d727cab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa22b5bc7c289f5f6a6ab7aa38025725835c096e8ffdeb39f71e61a1d551b8070e217197b8673cae5ea265a94a468a0d7a5baf58287d8412d77bbb1292b72305", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/590e49919a21e38d522d8e6a43ad57987fc864347aaae997df383a5a41aa6897f28db7dceb351740c6fa76bf462e715a9919c69fe9ce1eeaccdfaf758df28257", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df74ffac092313587052dcf6643967bfa5858d776c357b27c72025d2ef6e339fc348cfb3203a58f1ac821442c0f473167460eed2ca7cae163ee5be156f8ef14a"} 04:48:35 INFO - Running post-run listener: copy_logs_to_upload_dir 04:48:35 INFO - Copying logs to upload dir... 04:48:35 INFO - mkdir: /builds/slave/test/build/upload/logs 04:48:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1330.808648 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2017-05-29 04:48:36.121592) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:48:36.124972) ========= 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.K06SeZ4f48/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.n9uqKclqyZ/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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/03c21d1217cf053929a43595dcb1092fb80fa36a54f455270b6c63e46a2e97d7743145277c63462128e2c07938b6160e207e00a90cc1f3f63c51e2080d727cab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa22b5bc7c289f5f6a6ab7aa38025725835c096e8ffdeb39f71e61a1d551b8070e217197b8673cae5ea265a94a468a0d7a5baf58287d8412d77bbb1292b72305", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/590e49919a21e38d522d8e6a43ad57987fc864347aaae997df383a5a41aa6897f28db7dceb351740c6fa76bf462e715a9919c69fe9ce1eeaccdfaf758df28257", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df74ffac092313587052dcf6643967bfa5858d776c357b27c72025d2ef6e339fc348cfb3203a58f1ac821442c0f473167460eed2ca7cae163ee5be156f8ef14a"} build_url:https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/D0MrH3obTpu_f9-CQxOMuA/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/03c21d1217cf053929a43595dcb1092fb80fa36a54f455270b6c63e46a2e97d7743145277c63462128e2c07938b6160e207e00a90cc1f3f63c51e2080d727cab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa22b5bc7c289f5f6a6ab7aa38025725835c096e8ffdeb39f71e61a1d551b8070e217197b8673cae5ea265a94a468a0d7a5baf58287d8412d77bbb1292b72305", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/590e49919a21e38d522d8e6a43ad57987fc864347aaae997df383a5a41aa6897f28db7dceb351740c6fa76bf462e715a9919c69fe9ce1eeaccdfaf758df28257", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df74ffac092313587052dcf6643967bfa5858d776c357b27c72025d2ef6e339fc348cfb3203a58f1ac821442c0f473167460eed2ca7cae163ee5be156f8ef14a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:48:36.162280) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:48:36.163009) ========= 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.K06SeZ4f48/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.n9uqKclqyZ/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.005358 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:48:36.216146) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:48:36.216861) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:48:36.217495) ========= ========= Total master_lag: 0.23 =========