builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1495209630.4 results: success (0) buildid: 20170519074619 builduid: d8260a392c3442a8a86777caa51ea529 revision: 61932206bf73a5c510f97eb3089b823b97b1141e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:30.400687) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:30.401121) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:30.401400) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:30.428259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:30.428901) ========= 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.g682vTr1P5/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.MMi8TvTjpE/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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-19 09:00:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.09M=0.001s 2017-05-19 09:00:30 (8.09 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120317 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:30.580208) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:30.580573) ========= 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.g682vTr1P5/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.MMi8TvTjpE/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.020881 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:30.629131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 09:00:30.629512) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 61932206bf73a5c510f97eb3089b823b97b1141e --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 61932206bf73a5c510f97eb3089b823b97b1141e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g682vTr1P5/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.MMi8TvTjpE/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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-19 09:00:30,711 truncating revision to first 12 chars 2017-05-19 09:00:30,711 Setting DEBUG logging. 2017-05-19 09:00:30,711 attempt 1/10 2017-05-19 09:00:30,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/61932206bf73?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 09:00:32,151 unpacking tar archive at: mozilla-beta-61932206bf73/testing/mozharness/ program finished with exit code 0 elapsedTime=1.705544 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 09:00:32.359622) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:32.360150) ========= script_repo_revision: 61932206bf73a5c510f97eb3089b823b97b1141e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:32.360977) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:32.361415) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 09:00:32.380692) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-19 09:00:32.381129) ========= /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.g682vTr1P5/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.MMi8TvTjpE/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:00:32 INFO - MultiFileLogger online at 20170519 09:00:32 in /builds/slave/test 09:00:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:00:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:00:32 INFO - {'append_to_log': False, 09:00:32 INFO - 'base_work_dir': '/builds/slave/test', 09:00:32 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:00:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:00:32 INFO - 'branch': 'Mozilla-Beta', 09:00:32 INFO - 'buildbot_json_path': 'buildprops.json', 09:00:32 INFO - 'config_files': ('talos/mac_config.py',), 09:00:32 INFO - 'default_actions': ('clobber', 09:00:32 INFO - 'read-buildbot-config', 09:00:32 INFO - 'download-and-extract', 09:00:32 INFO - 'populate-webroot', 09:00:32 INFO - 'create-virtualenv', 09:00:32 INFO - 'install', 09:00:32 INFO - 'run-tests'), 09:00:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:00:32 INFO - 'download_minidump_stackwalk': True, 09:00:32 INFO - 'download_symbols': 'ondemand', 09:00:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:00:32 INFO - 'tooltool.py': '/tools/tooltool.py', 09:00:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:00:32 INFO - '/tools/misc-python/virtualenv.py')}, 09:00:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:00:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:00:32 INFO - 'gecko_profile': False, 09:00:32 INFO - 'gecko_profile_interval': 0, 09:00:32 INFO - 'installer_path': 'installer.exe', 09:00:32 INFO - 'log_level': 'info', 09:00:32 INFO - 'log_name': 'talos', 09:00:32 INFO - 'log_to_console': True, 09:00:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:00:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:00:32 INFO - 'opt_config_files': (), 09:00:32 INFO - 'pip_index': False, 09:00:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:32 INFO - 'cmd': ('bash', 09:00:32 INFO - '-c', 09:00:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:32 INFO - 'enabled': True, 09:00:32 INFO - 'halt_on_failure': False, 09:00:32 INFO - 'name': 'check_screen_resolution'},), 09:00:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:32 INFO - 'cmd': ('bash', 09:00:32 INFO - '-c', 09:00:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:32 INFO - 'enabled': True, 09:00:32 INFO - 'halt_on_failure': False, 09:00:32 INFO - 'name': 'check_screen_resolution'},), 09:00:32 INFO - 'run_cmd_checks_enabled': True, 09:00:32 INFO - 'suite': 'dromaeojs', 09:00:32 INFO - 'system_bits': '32', 09:00:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:00:32 INFO - 'title': 't-yosemite-r7-0018', 09:00:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:00:32 INFO - 'use_talos_json': True, 09:00:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:00:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:00:32 INFO - 'work_dir': 'build'} 09:00:32 INFO - [mozharness: 2017-05-19 16:00:32.580116Z] Running clobber step. 09:00:32 INFO - Running pre-action listener: _resource_record_pre_action 09:00:32 INFO - Running main action method: clobber 09:00:32 INFO - rmtree: /builds/slave/test/build 09:00:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:00:32 INFO - Running post-action listener: _resource_record_post_action 09:00:32 INFO - [mozharness: 2017-05-19 16:00:32.736366Z] Finished clobber step (success) 09:00:32 INFO - [mozharness: 2017-05-19 16:00:32.736492Z] Running read-buildbot-config step. 09:00:32 INFO - Running pre-action listener: _resource_record_pre_action 09:00:32 INFO - Running main action method: read_buildbot_config 09:00:32 INFO - Using buildbot properties: 09:00:32 INFO - { 09:00:32 INFO - "project": "", 09:00:32 INFO - "product": "firefox", 09:00:32 INFO - "script_repo_revision": "production", 09:00:32 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:00:32 INFO - "repository": "", 09:00:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:00:32 INFO - "buildid": "20170519074619", 09:00:32 INFO - "pgo_build": "False", 09:00:32 INFO - "basedir": "/builds/slave/test", 09:00:32 INFO - "buildnumber": 28, 09:00:32 INFO - "slavename": "t-yosemite-r7-0018", 09:00:32 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:00:32 INFO - "platform": "yosemite_r7", 09:00:32 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:00:32 INFO - "branch": "mozilla-beta", 09:00:32 INFO - "repo_path": "releases/mozilla-beta", 09:00:32 INFO - "revision": "61932206bf73a5c510f97eb3089b823b97b1141e", 09:00:32 INFO - "stage_platform": "macosx64", 09:00:32 INFO - "builduid": "d8260a392c3442a8a86777caa51ea529", 09:00:32 INFO - "slavebuilddir": "test" 09:00:32 INFO - } 09:00:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:00:32 INFO - Running post-action listener: _resource_record_post_action 09:00:32 INFO - [mozharness: 2017-05-19 16:00:32.739909Z] Finished read-buildbot-config step (success) 09:00:32 INFO - [mozharness: 2017-05-19 16:00:32.740023Z] Running download-and-extract step. 09:00:32 INFO - Running pre-action listener: _resource_record_pre_action 09:00:32 INFO - Running main action method: download_and_extract 09:00:32 INFO - mkdir: /builds/slave/test/build/tests 09:00:32 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')]} 09:00:32 INFO - trying https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:00:32 INFO - Downloading https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/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 09:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/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 09:00:34 INFO - Downloaded 1287 bytes. 09:00:34 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:00:34 INFO - Using the following test package requirements: 09:00:34 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:00:34 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:00:34 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:00:34 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:00:34 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:00:34 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:00:34 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:00:34 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:00:34 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:00:34 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:00:34 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:00:34 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:00:34 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:00:34 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:00:34 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:00:34 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:00:34 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:00:34 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:00:34 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:00:34 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:00:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:00:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:00:34 INFO - Fetch https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:00:35 INFO - Expected file size: 51226921 09:00:35 INFO - Obtained file size: 51226921 09:00:51 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 09:00:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:00:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:00:51 INFO - Fetch https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:00:53 INFO - Expected file size: 51226921 09:00:53 INFO - Obtained file size: 51226921 09:01:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:01:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:01:08 INFO - Fetch https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:01:09 INFO - Expected file size: 14404449 09:01:09 INFO - Obtained file size: 14404449 09:01:09 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')]} 09:01:09 INFO - trying https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:01:09 INFO - Downloading https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:01:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/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 09:01:11 INFO - Downloaded 59542504 bytes. 09:01:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:01:11 INFO - mkdir: /builds/slave/test/properties 09:01:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:11 INFO - Contents: 09:01:11 INFO - build_url:https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:01:12 INFO - Running post-action listener: _resource_record_post_action 09:01:12 INFO - Running post-action listener: set_extra_try_arguments 09:01:12 INFO - [mozharness: 2017-05-19 16:01:12.146379Z] Finished download-and-extract step (success) 09:01:12 INFO - [mozharness: 2017-05-19 16:01:12.146571Z] Running populate-webroot step. 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: populate_webroot 09:01:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:12 INFO - '--tppagecycles', 09:01:12 INFO - '7'], 09:01:12 INFO - 'tests': ['tsvgm']}}, 09:01:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:01:12 INFO - 'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:01:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:01:12 INFO - 'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['damp', 'tps']}, 09:01:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:01:12 INFO - 'tests': ['damp', 'tps']}, 09:01:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['dromaeo_dom']}, 09:01:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['basic_compositor_video']}, 09:01:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:01:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['a11yr', 09:01:12 INFO - 'ts_paint', 09:01:12 INFO - 'tpaint', 09:01:12 INFO - 'sessionrestore', 09:01:12 INFO - 'sessionrestore_no_auto_restore', 09:01:12 INFO - 'tabpaint']}, 09:01:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:12 INFO - 'ts_paint', 09:01:12 INFO - 'tpaint', 09:01:12 INFO - 'sessionrestore', 09:01:12 INFO - 'sessionrestore_no_auto_restore', 09:01:12 INFO - 'tabpaint']}, 09:01:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:12 INFO - 'ts_paint', 09:01:12 INFO - 'tpaint', 09:01:12 INFO - 'sessionrestore', 09:01:12 INFO - 'sessionrestore_no_auto_restore', 09:01:12 INFO - 'tabpaint']}, 09:01:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:12 INFO - 'ts_paint', 09:01:12 INFO - 'tpaint', 09:01:12 INFO - 'sessionrestore', 09:01:12 INFO - 'sessionrestore_no_auto_restore', 09:01:12 INFO - 'tabpaint']}, 09:01:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['a11yr', 09:01:12 INFO - 'ts_paint', 09:01:12 INFO - 'tpaint', 09:01:12 INFO - 'sessionrestore', 09:01:12 INFO - 'sessionrestore_no_auto_restore', 09:01:12 INFO - 'tabpaint']}, 09:01:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['a11yr', 09:01:12 INFO - 'ts_paint', 09:01:12 INFO - 'tpaint', 09:01:12 INFO - 'sessionrestore', 09:01:12 INFO - 'sessionrestore_no_auto_restore', 09:01:12 INFO - 'tabpaint']}, 09:01:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['tsvgx', 09:01:12 INFO - 'tsvgr_opacity', 09:01:12 INFO - 'tart', 09:01:12 INFO - 'tscrollx', 09:01:12 INFO - 'cart', 09:01:12 INFO - 'tsvg_static']}, 09:01:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:12 INFO - 'tsvgr_opacity', 09:01:12 INFO - 'tart', 09:01:12 INFO - 'tscrollx', 09:01:12 INFO - 'cart', 09:01:12 INFO - 'tsvg_static']}, 09:01:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:01:12 INFO - 'talos_options': ['--disable-e10s'], 09:01:12 INFO - 'tests': ['tp5o']}, 09:01:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:01:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:01:12 INFO - 'talos_options': ['--disable-e10s', 09:01:12 INFO - '--xperf_path', 09:01:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:12 INFO - 'tests': ['tp5n']}, 09:01:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:01:12 INFO - 'talos_options': ['--xperf_path', 09:01:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:12 INFO - 'tests': ['tp5n']}}, 09:01:12 INFO - 'talos.zip': {'path': '', 09:01:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:12 INFO - Running post-action listener: _resource_record_post_action 09:01:12 INFO - [mozharness: 2017-05-19 16:01:12.166335Z] Finished populate-webroot step (success) 09:01:12 INFO - [mozharness: 2017-05-19 16:01:12.166482Z] Running create-virtualenv step. 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: create_virtualenv 09:01:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:12 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 09:01:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:12 INFO - Using real prefix '/tools/python27' 09:01:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:15 INFO - Installing pip.................done. 09:01:15 INFO - Return code: 0 09:01:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:01:16 INFO - Reading from file tmpfile_stdout 09:01:16 INFO - Output received: 09:01:16 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) 09:01:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:16 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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01:16 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 09:01:16 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 09:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:16 INFO - 'HOME': '/Users/cltbld', 09:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:16 INFO - 'LOGNAME': 'cltbld', 09:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:16 INFO - 'MOZ_NO_REMOTE': '1', 09:01:16 INFO - 'NO_EM_RESTART': '1', 09:01:16 INFO - 'PAGER': '/bin/cat', 09:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:16 INFO - 'PWD': '/builds/slave/test', 09:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:16 INFO - 'SHELL': '/bin/bash', 09:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:16 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:16 INFO - 'USER': 'cltbld', 09:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:16 INFO - 'XPC_FLAGS': '0x0', 09:01:16 INFO - 'XPC_SERVICE_NAME': '0', 09:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:16 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 09:01:16 INFO - Cleaning up... 09:01:16 INFO - Return code: 0 09:01:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:16 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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01:16 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 09:01:16 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 09:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:16 INFO - 'HOME': '/Users/cltbld', 09:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:16 INFO - 'LOGNAME': 'cltbld', 09:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:16 INFO - 'MOZ_NO_REMOTE': '1', 09:01:16 INFO - 'NO_EM_RESTART': '1', 09:01:16 INFO - 'PAGER': '/bin/cat', 09:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:16 INFO - 'PWD': '/builds/slave/test', 09:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:16 INFO - 'SHELL': '/bin/bash', 09:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:16 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:16 INFO - 'USER': 'cltbld', 09:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:16 INFO - 'XPC_FLAGS': '0x0', 09:01:16 INFO - 'XPC_SERVICE_NAME': '0', 09:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:16 INFO - Downloading/unpacking psutil>=3.1.1 09:01:16 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 09:01:16 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 09:01:16 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 09:01:16 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 09:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:18 INFO - Installing collected packages: psutil 09:01:18 INFO - Running setup.py install for psutil 09:01:18 INFO - building 'psutil._psutil_osx' extension 09:01: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 09:01:19 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 09:01:19 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 09:01:19 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 09:01:19 INFO - building 'psutil._psutil_posix' extension 09:01:19 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 09:01:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:01:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:01:19 INFO - ^ 09:01:19 INFO - 1 warning generated. 09:01:19 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 09:01:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:19 INFO - Successfully installed psutil 09:01:19 INFO - Cleaning up... 09:01:19 INFO - Return code: 0 09:01:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:01:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:01:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:19 INFO - 'HOME': '/Users/cltbld', 09:01:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:19 INFO - 'LOGNAME': 'cltbld', 09:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:19 INFO - 'MOZ_NO_REMOTE': '1', 09:01:19 INFO - 'NO_EM_RESTART': '1', 09:01:19 INFO - 'PAGER': '/bin/cat', 09:01:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:19 INFO - 'PWD': '/builds/slave/test', 09:01:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:19 INFO - 'SHELL': '/bin/bash', 09:01:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:19 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:19 INFO - 'USER': 'cltbld', 09:01:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:19 INFO - 'XPC_FLAGS': '0x0', 09:01:19 INFO - 'XPC_SERVICE_NAME': '0', 09:01:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:01: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 09:01: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 09:01: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 09:01: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 09:01:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:01:21 INFO - Installing collected packages: mozsystemmonitor 09:01:21 INFO - Running setup.py install for mozsystemmonitor 09:01:21 INFO - Successfully installed mozsystemmonitor 09:01:21 INFO - Cleaning up... 09:01:21 INFO - Return code: 0 09:01:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:01:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:21 INFO - 'HOME': '/Users/cltbld', 09:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:21 INFO - 'LOGNAME': 'cltbld', 09:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:21 INFO - 'MOZ_NO_REMOTE': '1', 09:01:21 INFO - 'NO_EM_RESTART': '1', 09:01:21 INFO - 'PAGER': '/bin/cat', 09:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:21 INFO - 'PWD': '/builds/slave/test', 09:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:21 INFO - 'SHELL': '/bin/bash', 09:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:21 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:21 INFO - 'USER': 'cltbld', 09:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:21 INFO - 'XPC_FLAGS': '0x0', 09:01:21 INFO - 'XPC_SERVICE_NAME': '0', 09:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:21 INFO - Downloading/unpacking jsonschema==2.5.1 09:01: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 09:01: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 09:01: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 09:01: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 09:01:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:01:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:01: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 09:01: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 09:01: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 09:01: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 09:01:23 INFO - Downloading functools32-3.2.3-2.tar.gz 09:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:01:23 INFO - warning: no files found matching '*.txt' 09:01:23 INFO - no previously-included directories found matching 'build' 09:01:23 INFO - no previously-included directories found matching 'dist' 09:01:23 INFO - no previously-included directories found matching '.git*' 09:01:23 INFO - Installing collected packages: jsonschema, functools32 09:01:23 INFO - Running setup.py install for functools32 09:01:23 INFO - warning: no files found matching '*.txt' 09:01:23 INFO - no previously-included directories found matching 'build' 09:01:23 INFO - no previously-included directories found matching 'dist' 09:01:23 INFO - no previously-included directories found matching '.git*' 09:01:23 INFO - Successfully installed jsonschema functools32 09:01:23 INFO - Cleaning up... 09:01:23 INFO - Return code: 0 09:01:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01: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')]} 09:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01: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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01: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 09:01: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 09:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:23 INFO - 'HOME': '/Users/cltbld', 09:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:23 INFO - 'LOGNAME': 'cltbld', 09:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:23 INFO - 'MOZ_NO_REMOTE': '1', 09:01:23 INFO - 'NO_EM_RESTART': '1', 09:01:23 INFO - 'PAGER': '/bin/cat', 09:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:23 INFO - 'PWD': '/builds/slave/test', 09:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:23 INFO - 'SHELL': '/bin/bash', 09:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:23 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:23 INFO - 'USER': 'cltbld', 09:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:23 INFO - 'XPC_FLAGS': '0x0', 09:01:23 INFO - 'XPC_SERVICE_NAME': '0', 09:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:01:23 INFO - Cleaning up... 09:01:23 INFO - Return code: 0 09:01:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01: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')]} 09:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01: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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01: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 09:01: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 09:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:23 INFO - 'HOME': '/Users/cltbld', 09:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:23 INFO - 'LOGNAME': 'cltbld', 09:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:23 INFO - 'MOZ_NO_REMOTE': '1', 09:01:23 INFO - 'NO_EM_RESTART': '1', 09:01:23 INFO - 'PAGER': '/bin/cat', 09:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:23 INFO - 'PWD': '/builds/slave/test', 09:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:23 INFO - 'SHELL': '/bin/bash', 09:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:23 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:23 INFO - 'USER': 'cltbld', 09:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:23 INFO - 'XPC_FLAGS': '0x0', 09:01:23 INFO - 'XPC_SERVICE_NAME': '0', 09:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:24 INFO - Downloading/unpacking blobuploader==1.2.4 09:01:24 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 09:01:24 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 09:01:24 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 09:01:24 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 09:01:25 INFO - Downloading blobuploader-1.2.4.tar.gz 09:01:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:01:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:01: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 09:01: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 09:01: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 09:01: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 09:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:01:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:26 INFO - Downloading docopt-0.6.1.tar.gz 09:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:26 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:26 INFO - Running setup.py install for blobuploader 09:01:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:01:26 INFO - Running setup.py install for requests 09:01:27 INFO - Running setup.py install for docopt 09:01:27 INFO - Successfully installed blobuploader requests docopt 09:01:27 INFO - Cleaning up... 09:01:27 INFO - Return code: 0 09:01:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:28 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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01:28 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 09:01:28 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 09:01:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:28 INFO - 'HOME': '/Users/cltbld', 09:01:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:28 INFO - 'LOGNAME': 'cltbld', 09:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:28 INFO - 'MOZ_NO_REMOTE': '1', 09:01:28 INFO - 'NO_EM_RESTART': '1', 09:01:28 INFO - 'PAGER': '/bin/cat', 09:01:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:28 INFO - 'PWD': '/builds/slave/test', 09:01:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:28 INFO - 'SHELL': '/bin/bash', 09:01:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:28 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:28 INFO - 'USER': 'cltbld', 09:01:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:28 INFO - 'XPC_FLAGS': '0x0', 09:01:28 INFO - 'XPC_SERVICE_NAME': '0', 09:01:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-jzL2tT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-ccxtOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Ham_DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-1ZKFVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-mqKsug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-fdj2Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-bOmu4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-7XxvWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-gT8BJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-tygZUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-88aAZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-dMA6KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-QqKEyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-5WRebU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:29 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-OC_HrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:30 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Khx_O4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:30 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-SOtICY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:30 INFO - Running setup.py install for manifestparser 09:01:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:30 INFO - Running setup.py install for mozcrash 09:01:30 INFO - Running setup.py install for mozdebug 09:01:30 INFO - Running setup.py install for mozdevice 09:01:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:30 INFO - Running setup.py install for mozfile 09:01:31 INFO - Running setup.py install for mozhttpd 09:01:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:31 INFO - Running setup.py install for mozinfo 09:01:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:31 INFO - Running setup.py install for mozInstall 09:01:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:31 INFO - Running setup.py install for mozleak 09:01:31 INFO - Running setup.py install for mozlog 09:01:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:31 INFO - Running setup.py install for moznetwork 09:01:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:31 INFO - Running setup.py install for mozprocess 09:01:32 INFO - Running setup.py install for mozprofile 09:01:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:32 INFO - Running setup.py install for mozrunner 09:01:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:32 INFO - Running setup.py install for mozscreenshot 09:01:32 INFO - Running setup.py install for moztest 09:01:32 INFO - Running setup.py install for mozversion 09:01:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:32 INFO - Cleaning up... 09:01:32 INFO - Return code: 0 09:01:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:32 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')]} 09:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:32 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')]} 09:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:33 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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01:33 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 09:01:33 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 09:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:33 INFO - 'HOME': '/Users/cltbld', 09:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:33 INFO - 'LOGNAME': 'cltbld', 09:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:33 INFO - 'MOZ_NO_REMOTE': '1', 09:01:33 INFO - 'NO_EM_RESTART': '1', 09:01:33 INFO - 'PAGER': '/bin/cat', 09:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:33 INFO - 'PWD': '/builds/slave/test', 09:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:33 INFO - 'SHELL': '/bin/bash', 09:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:33 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:33 INFO - 'USER': 'cltbld', 09:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:33 INFO - 'XPC_FLAGS': '0x0', 09:01:33 INFO - 'XPC_SERVICE_NAME': '0', 09:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:33 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-K_3RRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:33 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)) 09:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:33 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-nBNllJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:33 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)) 09:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:33 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-jlZHtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:33 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)) 09:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:33 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-GgWf4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-dC71Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-jdwIo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-3T3a84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-UxU10V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-DspT2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-ZVgD7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-m9yhJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-VLG0uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:34 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)) 09:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:34 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-25ysYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:35 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)) 09:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:35 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-S5njXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:35 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)) 09:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:35 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-JTG5qI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:35 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)) 09:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:35 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-oXDp9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:35 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)) 09:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:35 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-xAK90A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:35 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)) 09:01:35 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)) 09:01:35 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)) 09:01:35 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)) 09:01:35 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)) 09:01:35 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)) 09:01:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:35 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 09:01:35 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 09:01:35 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 09:01:35 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 09:01:37 INFO - Downloading blessings-1.6.tar.gz 09:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:37 INFO - Installing collected packages: blessings 09:01:37 INFO - Running setup.py install for blessings 09:01:37 INFO - Successfully installed blessings 09:01:37 INFO - Cleaning up... 09:01:37 INFO - Return code: 0 09:01:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:37 INFO - Reading from file tmpfile_stdout 09:01:37 INFO - Current package versions: 09:01:37 INFO - blessings == 1.6 09:01:37 INFO - blobuploader == 1.2.4 09:01:37 INFO - docopt == 0.6.1 09:01:37 INFO - functools32 == 3.2.3-2 09:01:37 INFO - jsonschema == 2.5.1 09:01:37 INFO - manifestparser == 1.1 09:01:37 INFO - mozInstall == 1.12 09:01:37 INFO - mozcrash == 1.0 09:01:37 INFO - mozdebug == 0.1 09:01:37 INFO - mozdevice == 0.49 09:01:37 INFO - mozfile == 1.2 09:01:37 INFO - mozhttpd == 0.7 09:01:37 INFO - mozinfo == 0.9 09:01:37 INFO - mozleak == 0.1 09:01:37 INFO - mozlog == 3.4 09:01:37 INFO - moznetwork == 0.27 09:01:37 INFO - mozprocess == 0.25 09:01:37 INFO - mozprofile == 0.28 09:01:37 INFO - mozrunner == 6.13 09:01:37 INFO - mozscreenshot == 0.1 09:01:37 INFO - mozsystemmonitor == 0.3 09:01:37 INFO - moztest == 0.8 09:01:37 INFO - mozversion == 1.4 09:01:37 INFO - psutil == 3.1.1 09:01:37 INFO - requests == 1.2.3 09:01:37 INFO - wsgiref == 0.1.2 09:01:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:37 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')]} 09:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:37 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')]} 09:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:37 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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01:37 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 09:01:37 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 09:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:37 INFO - 'HOME': '/Users/cltbld', 09:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:37 INFO - 'LOGNAME': 'cltbld', 09:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:37 INFO - 'MOZ_NO_REMOTE': '1', 09:01:37 INFO - 'NO_EM_RESTART': '1', 09:01:37 INFO - 'PAGER': '/bin/cat', 09:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:37 INFO - 'PWD': '/builds/slave/test', 09:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:37 INFO - 'SHELL': '/bin/bash', 09:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:37 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:37 INFO - 'USER': 'cltbld', 09:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:37 INFO - 'XPC_FLAGS': '0x0', 09:01:37 INFO - 'XPC_SERVICE_NAME': '0', 09:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 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)) 09:01:38 INFO - Cleaning up... 09:01:38 INFO - Return code: 0 09:01:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01:38 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')]} 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:38 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')]} 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:38 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 0x10a68db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59465f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, '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.g682vTr1P5/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.MMi8TvTjpE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 09:01:38 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 09:01:38 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 09:01:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:38 INFO - 'HOME': '/Users/cltbld', 09:01:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:38 INFO - 'LOGNAME': 'cltbld', 09:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:38 INFO - 'MOZ_NO_REMOTE': '1', 09:01:38 INFO - 'NO_EM_RESTART': '1', 09:01:38 INFO - 'PAGER': '/bin/cat', 09:01:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:38 INFO - 'PWD': '/builds/slave/test', 09:01:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:38 INFO - 'SHELL': '/bin/bash', 09:01:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:38 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:38 INFO - 'USER': 'cltbld', 09:01:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:38 INFO - 'XPC_FLAGS': '0x0', 09:01:38 INFO - 'XPC_SERVICE_NAME': '0', 09:01:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:01:38 INFO - Cleaning up... 09:01:38 INFO - Return code: 0 09:01:38 INFO - Running post-action listener: _resource_record_post_action 09:01:38 INFO - Running post-action listener: _start_resource_monitoring 09:01:38 INFO - Starting resource monitoring. 09:01:38 INFO - [mozharness: 2017-05-19 16:01:38.361944Z] Finished create-virtualenv step (success) 09:01:38 INFO - [mozharness: 2017-05-19 16:01:38.362973Z] Running install step. 09:01:38 INFO - Running pre-action listener: _resource_record_pre_action 09:01:38 INFO - Running main action method: install 09:01:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:38 INFO - Reading from file tmpfile_stdout 09:01:38 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:38 INFO - Reading from file tmpfile_stdout 09:01:38 INFO - Output received: 09:01:38 INFO - Usage: mozinstall [options] installer 09:01:38 INFO - Options: 09:01:38 INFO - -h, --help show this help message and exit 09:01:38 INFO - -d DEST, --destination=DEST 09:01:38 INFO - Directory to install application into. [default: 09:01:38 INFO - "/builds/slave/test"] 09:01:38 INFO - --app=APP Application being installed. [default: firefox] 09:01:38 INFO - mkdir: /builds/slave/test/build/application 09:01:38 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'] 09:01:38 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 09:01:53 INFO - Reading from file tmpfile_stdout 09:01:53 INFO - Output received: 09:01:53 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:01:53 INFO - Running post-action listener: _resource_record_post_action 09:01:53 INFO - [mozharness: 2017-05-19 16:01:53.336173Z] Finished install step (success) 09:01:53 INFO - [mozharness: 2017-05-19 16:01:53.336303Z] Running run-tests step. 09:01:53 INFO - Running pre-action listener: _resource_record_pre_action 09:01:53 INFO - Running main action method: run_tests 09:01:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:01:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:01:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:01:53 INFO - 2017-05-19 09:01:53.351 screenresolution[1886:8652] starting screenresolution argv=screenresolution get 09:01:53 INFO - 2017-05-19 09:01:53.353 screenresolution[1886:8652] Display 0: 1600x1200x32@60 09:01:53 INFO - 2017-05-19 09:01:53.358 screenresolution[1887:8654] starting screenresolution argv=screenresolution list 09:01:53 INFO - Available Modes on Display 0 09:01:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:01:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:01:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:01:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:01:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:01:53 INFO - Intel Iris: 09:01:53 INFO - Chipset Model: Intel Iris 09:01:53 INFO - Type: GPU 09:01:53 INFO - Bus: Built-In 09:01:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:01:53 INFO - Vendor: Intel (0x8086) 09:01:53 INFO - Device ID: 0x0a2e 09:01:53 INFO - Revision ID: 0x0009 09:01:53 INFO - Displays: 09:01:53 INFO - Display: 09:01:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:01:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:01:53 INFO - Display Serial Number: 1600x1200 60 09:01:53 INFO - Main Display: Yes 09:01:53 INFO - Mirror: Off 09:01:53 INFO - Online: Yes 09:01:53 INFO - Rotation: Supported 09:01:53 INFO - Return code: 0 09:01:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:53 INFO - Python 2.7.3 09:01:53 INFO - Return code: 0 09:01:53 INFO - grabbing minidump binary from tooltool 09:01:53 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')]} 09:01:53 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 0x10a46bd30>, '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 0x10a392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a460be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:53 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 09:01:53 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 09:01:53 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 09:01:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:53 INFO - Return code: 0 09:01:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:01:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:53 INFO - ENV: RUST_BACKTRACE is now 1 09:01:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:01:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:53 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/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 09:01:53 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/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --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 09:01:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g682vTr1P5/Render', 09:01:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:53 INFO - 'HOME': '/Users/cltbld', 09:01:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PAGER': '/bin/cat', 09:01:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test', 09:01:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:53 INFO - 'RUST_BACKTRACE': '1', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMi8TvTjpE/Listeners', 09:01:53 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 09:01:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - 'XPC_FLAGS': '0x0', 09:01:53 INFO - 'XPC_SERVICE_NAME': '0', 09:01:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:53 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/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 09:01:53 INFO - mozversion application_buildid: 20170519074619 09:01:53 INFO - mozversion application_changeset: 61932206bf73a5c510f97eb3089b823b97b1141e 09:01:53 INFO - mozversion application_display_name: Firefox 09:01:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:53 INFO - mozversion application_name: Firefox 09:01:53 INFO - mozversion application_remotingname: firefox 09:01:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:01:53 INFO - mozversion application_vendor: Mozilla 09:01:53 INFO - mozversion application_version: 54.0 09:01:53 INFO - mozversion platform_buildid: 20170519074619 09:01:53 INFO - mozversion platform_changeset: 61932206bf73a5c510f97eb3089b823b97b1141e 09:01:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:01:53 INFO - mozversion platform_version: 54.0 09:01:53 INFO - using testdate: 1495209713 09:01:53 INFO - actual date: 1495209713 09:01:53 INFO - starting webserver on 'localhost:49344' 09:01:53 INFO - SUITE-START | Running 2 tests 09:01:53 INFO - TEST-START | dromaeo_css 09:01:53 INFO - operating with platform_type : mac_ 09:01:53 INFO - Initialising browser for dromaeo_css test... 09:01:53 INFO - TEST-INFO | started process 1897 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpUhCOLT/profile http://localhost:49344/getInfo.html) 09:01:56 INFO - PROCESS | 1897 | __metrics Screen width/height:1600/1200 09:01:56 INFO - PROCESS | 1897 | colorDepth:24 09:01:56 INFO - PROCESS | 1897 | Browser inner width/height: 1024/689 09:01:56 INFO - PROCESS | 1897 | __metrics 09:01:56 INFO - PROCESS | 1897 | 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" 09:01:56 INFO - PROCESS | 1897 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:01:57 INFO - TEST-INFO | 1897: exit 0 09:01:57 INFO - Browser initialized. 09:01:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:57 INFO - TEST-INFO | started process 1898 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpUhCOLT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:09 INFO - PROCESS | 1898 | RSS: Main: 165060608 09:02:09 INFO - PROCESS | 1898 | 09:05:13 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49344/tests/dromaeo/cssquery-ext.html) 09:05:14 INFO - PROCESS | 1898 | RSS: Main: 307945472 09:05:14 INFO - PROCESS | 1898 | 09:08:17 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-ext.html (next: http://localhost:49344/tests/dromaeo/cssquery-jquery.html) 09:08:18 INFO - PROCESS | 1898 | RSS: Main: 301228032 09:08:18 INFO - PROCESS | 1898 | 09:11:20 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49344/tests/dromaeo/cssquery-mootools.html) 09:11:21 INFO - PROCESS | 1898 | RSS: Main: 292020224 09:11:21 INFO - PROCESS | 1898 | 09:14:23 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49344/tests/dromaeo/cssquery-prototype.html) 09:14:24 INFO - PROCESS | 1898 | RSS: Main: 275460096 09:14:24 INFO - PROCESS | 1898 | 09:17:27 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49344/tests/dromaeo/cssquery-yui.html) 09:17:28 INFO - PROCESS | 1898 | RSS: Main: 268099584 09:17:28 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-yui.html (next: http://localhost:49344/tests/dromaeo/cssquery-dojo.html) 09:20:31 INFO - PROCESS | 1898 | RSS: Main: 383684608 09:20:31 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | __start_tp_report 09:20:31 INFO - PROCESS | 1898 | _x_x_mozilla_page_load 09:20:31 INFO - PROCESS | 1898 | _x_x_mozilla_page_load_details 09:20:31 INFO - PROCESS | 1898 | |i|pagename|runs| 09:20:31 INFO - PROCESS | 1898 | |0;dojo.html;3489.1277180704824;3513.7189024878003;3535.787852728835;3575.070481674764;3597.496350510929;6126.509879209663;6136.079588061793;6164.969175154123;6165.722542485588;6221.25344958605;6128.9387106128925;6187.226596675416;6204.193454850874;6214;6229.252489701231;5490.533304669098;5514.503694667479;5559.527440167591;5565.2486914266665;5659.717014149276;5438.211459338394;5452.59105567082;5482.862928426782;5646.435356464362;5667.489925906668;5553.361363443195;5558.305211848519;5559.9444005560035;5567.4432556744405;5583.190437386576;5612.663240205601;5619.690916999563;5625.6905870177125;5639.379668236491;5650.773969041234;5520.239551934426;5559.249501317311;5573.554115670777;5594.300712410949;5594.440555944414;5969.671668058299;5977.671228082454;6057.757689692451;6220.813375598694;6240.656763877985;5697.715114244271;6245.875082498324;6344.968275158641;6377.8405539861415;6410;6016.2479690038745;6531.183602049744;6536.444402225817;6582.637954912478;6586.901196482057;5453.64551304164;6243.906341404882;6329.9872020476505;6334.619922804646;6345.07996340528;6249.34381889895;6537.738490460423;6630.204375474974;6658.833529161713;6681.365270299314;5405.918911216335;6381.776637817654;6409.935900640934;6430.0676401921455;6431.839204019937;6477.546571739933;6529.314421985718;6564.573302735307;6581.835454113685;6583.440407565315;6201.720922558496;6247.031710084944;6336.809895703136;6361.650109244036;6446.129772480655;6208.441240288395;6213.565050446426;6277.748890044439;6443.516736244706;6471.0940468334475;5208.5312321891215;5213.296205012351;5221.190715439113;5238.371395432497;5243.10867152585;4670.579647831712;4676;4719.433667959798;4726.432828060655;4778.522147785194;6199.442050215503;6207.131001659771;6219;6219.191505104398;6229.221347331583;6277.435030847246;6351.745930162834;6476.190476190476;6490.8052758417325;6554.934450655528;6237.594556353823;6239.438450539473;6244.344343843922;6248.094026366242;6262.530310226751;5777;5787.363390027093;5796.82609521715;5806.88386232273;5843.123531470228;5605.663660180402;6471.288158302489;6511.446527045065;6650.434713049445;6745.59526428406;6529.8694026120165;6599.076129341992;6605.768798092236;6608.471322294301;6626.90059649096;6768;6768.4585233182215;6780.69486873082;6783.152105986752;6803.863922721419;6253.468455181361;6311.842203944938;6346.048092786119;6376.011718183689;6382.648954307558;6485.416312531895;6528.37980391862;6542;6545.836354091186;6555.311692272434;6264.31092579825;6340.239171299289;6349.682515874096;6350.841228969312;6375.904361434489;5968.731407086605;6254.968725156527;6263.906041409291;6269.185005949197;6314.336994615499;6272.843178920563;6322.083297921684;6330.651814150266;6331.588446750947;6346.492280617447;6314.989601663712;6346.777862774781;6369.044643303541;6370.522210834299;6370.58591191571;5470.945290547043;5976.701164941648;6214.782482613267;6280.685965701605;6322.146510221061 09:20:31 INFO - PROCESS | 1898 | |1;ext.html;781.394419325023;829.9751007469775;845.1548451548451;900.5227229568331;910.6266430763388;30324.241893952636;43298;43341.56658433415;43424.565754342446;43523.12953740923;43621.7818910905;43898.78050609743;43907.3413898791;43949;44197.337039944425;8004.159563245863;8119.309858662021;9653.179479744213;9834.164096051809;9839.852402213972;11937.164398492108;12018.759624807499;12280.078994075435;12306.81539776904;12310.507579696847;12124.818127728091;12125.939370303136;12137.514499420013;12161.027117830596;12163.331016794073;12070.155089143764;12071.698207544794;12112.454939527743;12112.6366209013;12155.331456769874;11722.824157637642;11760.411979400995;11781.469833857496;11788.997935175521;11793.29240245588;41304.79347603243;41357.586424135676;41431.75704728863;41445.37831932523;41499.1700165998;3567.7680950738118;3985.362342025291;4101.4668093148;4124.381342798574;4137.193247316774;28489.430211395862;28882.566761498594;30135.397292054255;30955.380892382025;30967.0709878704;2925.7220564046593;3181.9363612727616;3312.685294896981;3353.6478669739813;3442.621311655716;360498;361249;361271;361635.19182404183;362088.75822483405;54018;54157.91684166295;54189;54320;54350.45649543533;56496;56618.716906416026;56684.43315566792;56688.43311566914;56739;15440.84559154394;15543.378264869278;15554.844451555566;15559.533214003599;15577.454789082327;11927.88072119285;12078.396080195955;13222.735545289233;13239.73520529584;13250.939924805993;12620.242785432903;12834.358282086045;13042.282674452807;13047.543335983195;13049.934750326187;5613.46672066153;5829.300483941955;5843.883122337529;5848.239728835224;5857.297124345021;980.2794945714894;1139.7378602921208;1146.8337091121741;1191.845060142176;1212.9575464858688;23599.29202123939;28028.159155224967;28178.591070446397;28714.85642571774;28787.28031799222;972.4602845420718;1037.0459177556666;1082.2532452607675;1161.8199179127248;1334.2594859852682;15146.394144234328;15310.617234569225;15439.30523126462;15820.525384238492;16114.952528085636;481094;485962;486743;487053;488126.1187388152;47808.04383912302;59623;59762;59787.40212597905;59792.40207597955;1466;1502.3164460170378;1504.7216264991011;1513.1526345246698;1515.6589767302269;25368.873155634104;29896.50517474161;31859.681403186598;32029.71881124822;32472.350552988337;49719.25421118759;49805;49935.500644994536;49975.500244997085;50039.74900627513;470542.7092967973;476653;477373;478150;478742;31128.68871311258;33811;34397.140071497415;34424.31151377009;34513.48229776606;46288.30567541557;46895.531044689116;47478.52521474741;47883;48174.5182548184;31064.844675776476;31779.36441271208;31879.52180717245;32556.837215814718;33010.66989330076;31281.217969550034;31389.68610313959;31454;31772.523412148374;32407.51388729218;36592.45111323279;37582.624173759;37679.24641507209;37985.4302185473;38376.6162338373;25061.498770024133;31933.68066319307;32679;32696.34607307888;32940.50589241115;23758.762412375654;24539.141130061078;24731.876340618182;25170.74829251684;25486.490270194863 09:20:31 INFO - PROCESS | 1898 | |2;jquery.html;415.37900838246816;433.7010653093984;441.41071669321445;446.718205995533;451.6973627669463;20888.89555552222;21979.78020219799;22186;23025.194118205865;23178.42053948652;22446.663300050513;23372.948217330217;23520.411989700267;23548.64677029846;23828.927698253578;6838.555493892906;6899.37905588497;6906;7036.261192574782;7042.6126563039015;6632.436242919358;6947.617881016542;6951.409130223937;6961.895571566431;6963.408110310605;6601.438877695402;6862.107925969642;6916.066331045295;6945.131858517685;6947.513674042793;6777.525573209878;6849.760258390932;6876.828079298008;6935.791926242196;6943.618101004443;6718.563293385914;6720.126383570154;6733.360330768618;6735.292794256581;6737.966310168466;27252.59121113185;27638.309042274104;27677.86161069202;27698.5845212322;27713.168604941882;2928.633920759905;3022.1689035515187;3026.228311780504;3055.4500189965797;3098.721115099652;16337.673246535122;16509.83490165107;17012.574685632957;17048.82951170497;17831.554211144696;1875.4279944616887;1938.7673479182588;1951.5023668964466;1986.3345779163997;1990.7212990181386;116571;120042.3997880005;120112;120173;120494;19181.424557263304;19932.402027939184;20062;20261.392158235278;21469.892650536647;31285.687143128733;31707.890223842056;31748.523772143435;31841.363172736415;31990.84004579962;10225.233107516966;10450.477476126316;10607.84088238677;10609.787804244026;10801.379793031108;8944.418612790278;8952.373333866697;8967.686130985385;9137.360384773003;9148.039455857039;9300.674476393293;9365.765855853659;9437.764055898657;9451.669191578261;9487.620495180116;3379.391709492315;3883.4757307763657;3917.845532900544;3944.5660977292473;3951.7233793634746;1054.283087500507;1094.1520321750706;1095.687728997232;1108.6031600183826;1151.7523732397574;22159.12215362305;22414;22447;22469.88765056197;22624.208152714575;1020.1175982774846;1084.9077828384518;1100.884407137255;1100.9669709908715;1153.6885041038872;11188.328700278009;11634.476448559835;11675.104072443532;11748.706282342839;11792.23350482216;747040;759843;775382;800359;819830;152537;157889;160870;161715;164381;1268.4355461819403;1295.1387327427155;1297.5718013055669;1326.562234462625;1327.5751759436887;18848.717269240697;19892.403227903185;19895.303664371677;19943.401697949084;19977.90121543271;65860.0120998195;66455.66772166108;66825.66587167032;68349;68721.65639171773;94227.52886235525;95325.5233723827;95640.5653915178;96431.11246000728;97353;10447.634332798316;11039.944800275947;11103.38931358783;11446.37044962535;11564.94217528907;12832.973362131193;12876.871231287567;12965.286909220084;13040.152390094614;13421.127626704232;11237.693951314883;11738.41307934617;11822.995045420892;11825.763484730429;11831.645050648494;10281.74295642615;10289.588416463257;10528.736781580521;10570.577176912837;10748.570057197625;9555.761105972406;11564.94217528907;11625.709357265798;11700.239484433485;11802.94098529502;11620.593279235525;11625;11654.658446606682;11864;11900.821487677866;10205.642802501878;10944.945275273572;11220.943895280472;11728.237664551776;11743.236689615147 09:20:31 INFO - PROCESS | 1898 | |3;mootools.html;1415.6814716688739;1613.1934032983502;1638.9508314750562;1647.5716313758426;1652.9173541322932;9438.952805235973;9861.802763944726;9871.703848884526;9908.603655853758;9908.702738917826;9774.218062554995;10954.178436617245;11215.775684486325;11454;11456.770864582704;2465.2357769091595;2511.309389917769;2514.446821699223;2514.5976643737004;2515.71069327027;2060.917563297466;2368.549975504657;2491.638712386703;2494.638277449769;2497.325722055044;2152.9031193596325;2170.1536400803698;2174.5976994256034;2178.2593918067855;2178.814800741939;2227.498812767131;2293.5068960173558;2300.0454811253308;2302.6200676888293;2306.7693230676964;2417.782399584028;2445.0097710427303;2447.9510409791706;2449.2652204338806;2452.1907770435714;11258.155638327158;11516.30902145874;11528.481218345194;11554.364509951949;11577.247478913843;5929.73316200772;6107.144999700045;6111.480524155452;6111.816645500642;6112.541559383064;3455.4989526518516;3771.7359784815076;3820.1977584707247;3842.9615703842883;3886.8833934981994;1282.9743405131937;1737.6785294720517;1892.167446323627;1925.6341295153875;1927.787943326233;15922.76115858263;16075.678486430323;16108.516744497683;16114.355425783071;16130.354785808553;4067.8372865085066;4687.086018226413;4833.564979151894;4886.755662216875;4927.187014142698;11738.119641026957;11756.177067605357;11764.176507644554;11793.351365674798;11815.2320099195;6020.69896505173;6022.247219097613;6026.819195424145;6032.547558933097;6041.18444010053;4602.654800889946;5249.133892907704;5415.918761218585;5497.45025497447;5510.118381059011;3864.14988702485;3948.881533553998;3976.5426975897562;4015;4031.294523458383;943.1841457139525;1052.0373857919917;1064.2656566968922;1067.215596536545;1083.3012706804225;921.6129225725211;935.4621092871625;944.9291303152291;946.8153710026479;965.2374624046925;6333.746650134035;6452.35476452351;6499.805005849832;6581.440577550962;6804.897926531106;922.276013329533;974.2887691984749;1039.7660526381503;1045.6078970386104;1054.8681414823147;3952.664023557972;3994.5206575210577;4122.958770412318;4152.896177595524;4155.646770024582;13824.239666818421;14319.21244331551;14336.56990290293;14341.067830590979;14360.425582976774;14239;14243.85756142446;14253;14253.501127460697;14266.215358155194;9996.800063998824;10303.3817970922;10470.424126673106;10514.26400151982;10525.89474105249;5044.8991020178655;6017.488513476404;6398.200224971879;6450.612963222222;6531.11829902976;12216.75566488683;12455.688607784878;12657.303848288064;12721.681957951125;12766.29785361814;10927.180461465581;12647;13034.47862085506;13051.21692698441;13065.281409522187;8413.579321033802;8763.211310982042;8780.297576194016;8780.385373023826;8784.253338466302;10127.442990635585;10339.741506462398;10418.374897506174;10439.269251152346;10479.79040419172;8724.345674074342;8914.955425222832;8996;9056.338446607637;9107.817843643223;8661.566921654017;8669.046404895582;8718.607662655322;8736.951565812144;8793.956030219808;7848.84302313962;8005.599720014093;8018.4787988780545;8043.235892590194;8048.275655191061;5053.646244762831;5682.715864206856;5711.400302968294;5803.564732645153;5924.555658325557;5305.920411193758;5761.827145185651;5785.739641716049;5861.589688721749;5980.641161530322 09:20:31 INFO - PROCESS | 1898 | |4;prototype.html;394.36310358770584;394.5108065998163;400.1816285695752;400.82163437270407;401.8633664554051;4447.555244475551;4611.031683346497;5146.228065790134;5166.339335242354;5282.973585132078;4435.201663700533;4848.2242841145335;5031.34592502975;5177.223416487529;5178.5339319461245;3419.8543487931574;4279.786010699452;4312.568743125678;4331.43691320128;4340.609345158927;4048.6153815387493;4150.983009162759;4345.413369195166;4351.844912438489;4356.695031347807;3631.7858695054933;3857.189990102082;3895.7467764595353;4334.84828031019;4357.891052723676;3491.5810102787573;3715.9628403716156;4055.655269302113;4196.97901510492;4215.9670880634285;3415.8804441844663;3428.0058782952915;3873.0704630888667;3964.147708242742;3976.324024915771;4246.851360202409;5027.019731152427;5057.508035129646;5119.365250672027;5193.792248310063;4126.896827579335;4563.178627846986;4933.80264789408;5014.423341315759;5050.4848545436225;4974.9751251243515;5543.918935807519;5573.804916827932;5599.720013999325;5601.935632229904;4048.7246517347235;4206.768627725474;4221.641160501361;4278.657707383401;4365.825366985317;5059.418166910816;5648.181013753024;5746.793173433584;5886.9705651471895;5900.409959004108;4479.664025198058;4623.884402889955;4628.652851036186;4636.304554316879;4669.813207471731;4101.498086167495;4137.855175068858;4669.4396672399525;4730.739809310452;4775.379200703956;4199.349100889367;4554.339620754972;4943.8516844494725;4969.329140566049;5022.799088036438;5408.8377348679605;5423.755930983115;5436.91844622331;5454.963556924251;5486.533644640251;1420.5809286260578;1475.5352064099775;1494.8206215254238;1604.278074866315;1620.3518592563069;1041.6250549275035;1072.8980746829038;1100.8183649697871;1111.9777604447866;1132.133917553056;4754.286856971482;4820.855374338776;4981.526754958273;5236.214567814858;5273.288106105703;4711.882202944886;5257.083472812779;5634.774609015675;5658.830235092953;5669.659820410789;4659.254519276899;4750.648429027653;5171.362605071441;5172.612054095958;5235.895282094337;2805.957910631342;2891.7686585073143;2948.9705102949124;2985.3730716549335;3005.5191169412788;5517.420670829585;5553.750730953971;5882.264716910387;5911.881762364728;5928.407159284124;4568.223402021665;4799.904001919942;5126.641135120506;5132.589392848563;5497.3207552823515;4997.575206107449;5076.238564215323;5113.693178409308;5664.631798933056;5806;4827.08285425768;4979.103761322996;5193.818216362409;5374.167004114369;5684.459976302244;5088.465711100281;5143.897122057613;5567.972160139173;5664.697119662418;5708.914366284426;5274.472552744694;5327.467253274642;5527.351947714489;5773.04744717117;5812.622179558315;4334.869953901388;4843;5036.471170527042;5078.339815823919;5131;5116.872078198075;5239.633225674166;5409.756560954846;5791.276090488689;5838.678872661874;5268.57851371897;5442.891142177214;5472.288602481652;5612.678237756986;5637.464440878274;4805.519448055166;4863.586595139312;5336.852576695874;5529.364123125892;5575.526080283059;4342.544032876503;4559.338895860148;4776.713397196179;5390.272313237662;5526.728852363898;4962.330085438564;5097.337346145126;5268.76290566933;5670.971645141748;5708.80019199326;4690.859274221779;4969;5039.546440820343;5319.867003324948;5573.6098473106495 09:20:31 INFO - PROCESS | 1898 | |5;yui.html;772.4515593928311;780.8477346917347;886.3042511628369;886.6763631274584;910.244497067434;15799.368025278989;16702.83297167028;17428.215730292137;18095.4571362859;18101.637967240647;16131.83868161318;16142.7578586321;16184.676306473864;16555.91722041391;16636.25136868841;848.5714714069386;1007.3905287301187;1012.5443550402312;1016.7102375822892;1019.8011387779404;999.4852650884764;1006.5118417567473;1023.9606799098921;1026.6765968719867;1032.9122024627916;1073.8281874900017;1074.3339129739572;1074.8602681651375;1075.9462026898664;1076.7146706122844;1066.8186408310605;1068.1561566362564;1068.2362111090608;1068.9465526723632;1068.9679309620724;793.317746737805;799.8120441696196;800.5756948817136;802.7671975127206;804.8229389534293;13180.802287965687;17189.22648480809;17246.482605521855;17408.042557659322;17725.93644381341;14642.633934151732;14741;14816.259187040605;14857.925710371486;14874;12192.57325993586;12295.4467048983;12296.60911687989;12322.507099716;12614.180078294881;3815.618438156162;3819.1597848473293;3842.3083844907906;3932.7247092703524;3987.182627561343;92501;97670.69869104082;106143.46928265387;106182;107001.46499267532;11133.554657813758;11220.551177952953;11227;11249.83125253122;11496.42517874103;22663.433414164447;22675;22685.319440416813;22954.19660311881;23022.07911683514;7458.216887226871;7746.922530774733;7788.610569471503;8000.5599692017495;8039.758807235792;4565.954340456553;4574.931376029363;4614.377059097046;4643.6517261205545;4780.4741478437345;3073.6772638873044;3697.9445308320396;3722.181120153562;3729.0863738383728;3741.0273328934127;655.452696998012;656.1994366872408;662.7912207654573;672.5930811858852;675.3347952267013;685.8491131950963;706.494856177825;709.6558169287891;728.5373787644778;759.9240075992357;8576.44253123545;10613.363198208132;10916.290441121302;10955.83566246507;11038.503267352988;672.6132473827569;699.9650017499105;713.9071920650282;717.4188906985254;734.522560335786;4035.3946907963455;5038.118329292359;5055.469175736568;5441.945580544223;5649.4915457608195;92034.53982730178;99106.51340229902;106337.97542305586;108237.458812707;110708;52580.47419525832;59360;61155.38844611497;75160;92921;78640;78877.81683274756;79550.6022469907;80687.99999999882;81994;10950.39772812503;11121.83317250258;11228.550857965916;11366.318020918772;11394.25937314072;39494;47436.76281618708;47960;48864.0227195447;49307;44507.66192535508;63032.369676304486;63156;63287.6835615819;63389.36610633835;12457.183705483565;12865.935670321589;13042.804357934829;13512.459501619825;13675.79486307726;14799.112053276838;14893.627659308604;15738.527844164693;16139.031658100552;16311.265993030105;13849.723005539632;14506.419743210154;14516.854831451972;14833.406663733762;14987.100773953598;13887.097338672955;14001.439942402598;14312.499062532761;14420.062695924731;14963.925180374028;13187.076904616968;13224.537141200011;13876.722465550834;14022.368993395527;14260.073095248776;9613.375130616487;9981.401115933068;10599.205059620714;10929.56281748753;11009.889901100887;7700.845983080195;8951.686690965786;9079.727608171765;10652.84020739674;11603 09:20:31 INFO - PROCESS | 1898 | __end_tp_report 09:20:31 INFO - PROCESS | 1898 | __start_cc_report 09:20:31 INFO - PROCESS | 1898 | _x_x_mozilla_cycle_collect,1429 09:20:31 INFO - PROCESS | 1898 | __end_cc_report 09:20:31 INFO - PROCESS | 1898 | __startTimestamp1495210831667__endTimestamp 09:20:31 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | ------- Summary: start ------- 09:20:31 INFO - PROCESS | 1898 | Number of tests: 6 09:20:31 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | [#0] dojo.html Cycles:165 Average:6035.07 Median:6247.03 stddev:638.85 (10.2%) stddev-sans-first:608.79 09:20:31 INFO - PROCESS | 1898 | Values: 3489.1 3513.7 3535.8 3575.1 3597.5 6126.5 6136.1 6165.0 6165.7 6221.3 6128.9 6187.2 6204.2 6214.0 6229.3 5490.5 5514.5 5559.5 5565.2 5659.7 5438.2 5452.6 5482.9 5646.4 5667.5 5553.4 5558.3 5559.9 5567.4 5583.2 5612.7 5619.7 5625.7 5639.4 5650.8 5520.2 5559.2 5573.6 5594.3 5594.4 5969.7 5977.7 6057.8 6220.8 6240.7 5697.7 6245.9 6345.0 6377.8 6410.0 6016.2 6531.2 6536.4 6582.6 6586.9 5453.6 6243.9 6330.0 6334.6 6345.1 6249.3 6537.7 6630.2 6658.8 6681.4 5405.9 6381.8 6409.9 6430.1 6431.8 6477.5 6529.3 6564.6 6581.8 6583.4 6201.7 6247.0 6336.8 6361.7 6446.1 6208.4 6213.6 6277.7 6443.5 6471.1 5208.5 5213.3 5221.2 5238.4 5243.1 4670.6 4676.0 4719.4 4726.4 4778.5 6199.4 6207.1 6219.0 6219.2 6229.2 6277.4 6351.7 6476.2 6490.8 6554.9 6237.6 6239.4 6244.3 6248.1 6262.5 5777.0 5787.4 5796.8 5806.9 5843.1 5605.7 6471.3 6511.4 6650.4 6745.6 6529.9 6599.1 6605.8 6608.5 6626.9 6768.0 6768.5 6780.7 6783.2 6803.9 6253.5 6311.8 6346.0 6376.0 6382.6 6485.4 6528.4 6542.0 6545.8 6555.3 6264.3 6340.2 6349.7 6350.8 6375.9 5968.7 6255.0 6263.9 6269.2 6314.3 6272.8 6322.1 6330.7 6331.6 6346.5 6315.0 6346.8 6369.0 6370.5 6370.6 5470.9 5976.7 6214.8 6280.7 6322.1 09:20:31 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | [#1] ext.html Cycles:180 Average:59031.93 Median:32975.59 stddev:117919.91 (357.6%) stddev-sans-first:118169.60 09:20:31 INFO - PROCESS | 1898 | Values: 781.4 830.0 845.2 900.5 910.6 30324.2 43298.0 43341.6 43424.6 43523.1 43621.8 43898.8 43907.3 43949.0 44197.3 8004.2 8119.3 9653.2 9834.2 9839.9 11937.2 12018.8 12280.1 12306.8 12310.5 12124.8 12125.9 12137.5 12161.0 12163.3 12070.2 12071.7 12112.5 12112.6 12155.3 11722.8 11760.4 11781.5 11789.0 11793.3 41304.8 41357.6 41431.8 41445.4 41499.2 3567.8 3985.4 4101.5 4124.4 4137.2 28489.4 28882.6 30135.4 30955.4 30967.1 2925.7 3181.9 3312.7 3353.6 3442.6 360498.0 361249.0 361271.0 361635.2 362088.8 54018.0 54157.9 54189.0 54320.0 54350.5 56496.0 56618.7 56684.4 56688.4 56739.0 15440.8 15543.4 15554.8 15559.5 15577.5 11927.9 12078.4 13222.7 13239.7 13250.9 12620.2 12834.4 13042.3 13047.5 13049.9 5613.5 5829.3 5843.9 5848.2 5857.3 980.3 1139.7 1146.8 1191.8 1213.0 23599.3 28028.2 28178.6 28714.9 28787.3 972.5 1037.0 1082.3 1161.8 1334.3 15146.4 15310.6 15439.3 15820.5 16115.0 481094.0 485962.0 486743.0 487053.0 488126.1 47808.0 59623.0 59762.0 59787.4 59792.4 1466.0 1502.3 1504.7 1513.2 1515.7 25368.9 29896.5 31859.7 32029.7 32472.4 49719.3 49805.0 49935.5 49975.5 50039.7 470542.7 476653.0 477373.0 478150.0 478742.0 31128.7 33811.0 34397.1 34424.3 34513.5 46288.3 46895.5 47478.5 47883.0 48174.5 31064.8 31779.4 31879.5 32556.8 33010.7 31281.2 31389.7 31454.0 31772.5 32407.5 36592.5 37582.6 37679.2 37985.4 38376.6 25061.5 31933.7 32679.0 32696.3 32940.5 23758.8 24539.1 24731.9 25170.7 25486.5 09:20:31 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | [#2] jquery.html Cycles:180 Average:43742.66 Median:22069.45 stddev:129505.06 (586.8%) stddev-sans-first:129827.49 09:20:31 INFO - PROCESS | 1898 | Values: 415.4 433.7 441.4 446.7 451.7 20888.9 21979.8 22186.0 23025.2 23178.4 22446.7 23372.9 23520.4 23548.6 23828.9 6838.6 6899.4 6906.0 7036.3 7042.6 6632.4 6947.6 6951.4 6961.9 6963.4 6601.4 6862.1 6916.1 6945.1 6947.5 6777.5 6849.8 6876.8 6935.8 6943.6 6718.6 6720.1 6733.4 6735.3 6738.0 27252.6 27638.3 27677.9 27698.6 27713.2 2928.6 3022.2 3026.2 3055.5 3098.7 16337.7 16509.8 17012.6 17048.8 17831.6 1875.4 1938.8 1951.5 1986.3 1990.7 116571.0 120042.4 120112.0 120173.0 120494.0 19181.4 19932.4 20062.0 20261.4 21469.9 31285.7 31707.9 31748.5 31841.4 31990.8 10225.2 10450.5 10607.8 10609.8 10801.4 8944.4 8952.4 8967.7 9137.4 9148.0 9300.7 9365.8 9437.8 9451.7 9487.6 3379.4 3883.5 3917.8 3944.6 3951.7 1054.3 1094.2 1095.7 1108.6 1151.8 22159.1 22414.0 22447.0 22469.9 22624.2 1020.1 1084.9 1100.9 1101.0 1153.7 11188.3 11634.5 11675.1 11748.7 11792.2 747040.0 759843.0 775382.0 800359.0 819830.0 152537.0 157889.0 160870.0 161715.0 164381.0 1268.4 1295.1 1297.6 1326.6 1327.6 18848.7 19892.4 19895.3 19943.4 19977.9 65860.0 66455.7 66825.7 68349.0 68721.7 94227.5 95325.5 95640.6 96431.1 97353.0 10447.6 11039.9 11103.4 11446.4 11564.9 12833.0 12876.9 12965.3 13040.2 13421.1 11237.7 11738.4 11823.0 11825.8 11831.6 10281.7 10289.6 10528.7 10570.6 10748.6 9555.8 11564.9 11625.7 11700.2 11802.9 11620.6 11625.0 11654.7 11864.0 11900.8 10205.6 10944.9 11220.9 11728.2 11743.2 09:20:31 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | [#3] mootools.html Cycles:180 Average:6798.66 Median:3613.62 stddev:4310.21 (119.3%) stddev-sans-first:4303.32 09:20:31 INFO - PROCESS | 1898 | Values: 1415.7 1613.2 1639.0 1647.6 1652.9 9439.0 9861.8 9871.7 9908.6 9908.7 9774.2 10954.2 11215.8 11454.0 11456.8 2465.2 2511.3 2514.4 2514.6 2515.7 2060.9 2368.5 2491.6 2494.6 2497.3 2152.9 2170.2 2174.6 2178.3 2178.8 2227.5 2293.5 2300.0 2302.6 2306.8 2417.8 2445.0 2448.0 2449.3 2452.2 11258.2 11516.3 11528.5 11554.4 11577.2 5929.7 6107.1 6111.5 6111.8 6112.5 3455.5 3771.7 3820.2 3843.0 3886.9 1283.0 1737.7 1892.2 1925.6 1927.8 15922.8 16075.7 16108.5 16114.4 16130.4 4067.8 4687.1 4833.6 4886.8 4927.2 11738.1 11756.2 11764.2 11793.4 11815.2 6020.7 6022.2 6026.8 6032.5 6041.2 4602.7 5249.1 5415.9 5497.5 5510.1 3864.1 3948.9 3976.5 4015.0 4031.3 943.2 1052.0 1064.3 1067.2 1083.3 921.6 935.5 944.9 946.8 965.2 6333.7 6452.4 6499.8 6581.4 6804.9 922.3 974.3 1039.8 1045.6 1054.9 3952.7 3994.5 4123.0 4152.9 4155.6 13824.2 14319.2 14336.6 14341.1 14360.4 14239.0 14243.9 14253.0 14253.5 14266.2 9996.8 10303.4 10470.4 10514.3 10525.9 5044.9 6017.5 6398.2 6450.6 6531.1 12216.8 12455.7 12657.3 12721.7 12766.3 10927.2 12647.0 13034.5 13051.2 13065.3 8413.6 8763.2 8780.3 8780.4 8784.3 10127.4 10339.7 10418.4 10439.3 10479.8 8724.3 8915.0 8996.0 9056.3 9107.8 8661.6 8669.0 8718.6 8737.0 8794.0 7848.8 8005.6 8018.5 8043.2 8048.3 5053.6 5682.7 5711.4 5803.6 5924.6 5305.9 5761.8 5785.7 5861.6 5980.6 09:20:31 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | [#4] prototype.html Cycles:180 Average:4563.87 Median:4977.04 stddev:1268.43 (25.5%) stddev-sans-first:1232.78 09:20:31 INFO - PROCESS | 1898 | Values: 394.4 394.5 400.2 400.8 401.9 4447.6 4611.0 5146.2 5166.3 5283.0 4435.2 4848.2 5031.3 5177.2 5178.5 3419.9 4279.8 4312.6 4331.4 4340.6 4048.6 4151.0 4345.4 4351.8 4356.7 3631.8 3857.2 3895.7 4334.8 4357.9 3491.6 3716.0 4055.7 4197.0 4216.0 3415.9 3428.0 3873.1 3964.1 3976.3 4246.9 5027.0 5057.5 5119.4 5193.8 4126.9 4563.2 4933.8 5014.4 5050.5 4975.0 5543.9 5573.8 5599.7 5601.9 4048.7 4206.8 4221.6 4278.7 4365.8 5059.4 5648.2 5746.8 5887.0 5900.4 4479.7 4623.9 4628.7 4636.3 4669.8 4101.5 4137.9 4669.4 4730.7 4775.4 4199.3 4554.3 4943.9 4969.3 5022.8 5408.8 5423.8 5436.9 5455.0 5486.5 1420.6 1475.5 1494.8 1604.3 1620.4 1041.6 1072.9 1100.8 1112.0 1132.1 4754.3 4820.9 4981.5 5236.2 5273.3 4711.9 5257.1 5634.8 5658.8 5669.7 4659.3 4750.6 5171.4 5172.6 5235.9 2806.0 2891.8 2949.0 2985.4 3005.5 5517.4 5553.8 5882.3 5911.9 5928.4 4568.2 4799.9 5126.6 5132.6 5497.3 4997.6 5076.2 5113.7 5664.6 5806.0 4827.1 4979.1 5193.8 5374.2 5684.5 5088.5 5143.9 5568.0 5664.7 5708.9 5274.5 5327.5 5527.4 5773.0 5812.6 4334.9 4843.0 5036.5 5078.3 5131.0 5116.9 5239.6 5409.8 5791.3 5838.7 5268.6 5442.9 5472.3 5612.7 5637.5 4805.5 4863.6 5336.9 5529.4 5575.5 4342.5 4559.3 4776.7 5390.3 5526.7 4962.3 5097.3 5268.8 5671.0 5708.8 4690.9 4969.0 5039.5 5319.9 5573.6 09:20:31 INFO - PROCESS | 1898 | 09:20:31 INFO - PROCESS | 1898 | [#5] yui.html Cycles:180 Average:19903.10 Median:17910.70 stddev:27648.14 (154.4%) stddev-sans-first:27688.38 09:20:31 INFO - PROCESS | 1898 | Values: 772.5 780.8 886.3 886.7 910.2 15799.4 16702.8 17428.2 18095.5 18101.6 16131.8 16142.8 16184.7 16555.9 16636.3 848.6 1007.4 1012.5 1016.7 1019.8 999.5 1006.5 1024.0 1026.7 1032.9 1073.8 1074.3 1074.9 1075.9 1076.7 1066.8 1068.2 1068.2 1068.9 1069.0 793.3 799.8 800.6 802.8 804.8 13180.8 17189.2 17246.5 17408.0 17725.9 14642.6 14741.0 14816.3 14857.9 14874.0 12192.6 12295.4 12296.6 12322.5 12614.2 3815.6 3819.2 3842.3 3932.7 3987.2 92501.0 97670.7 106143.5 106182.0 107001.5 11133.6 11220.6 11227.0 11249.8 11496.4 22663.4 22675.0 22685.3 22954.2 23022.1 7458.2 7746.9 7788.6 8000.6 8039.8 4566.0 4574.9 4614.4 4643.7 4780.5 3073.7 3697.9 3722.2 3729.1 3741.0 655.5 656.2 662.8 672.6 675.3 685.8 706.5 709.7 728.5 759.9 8576.4 10613.4 10916.3 10955.8 11038.5 672.6 700.0 713.9 717.4 734.5 4035.4 5038.1 5055.5 5441.9 5649.5 92034.5 99106.5 106338.0 108237.5 110708.0 52580.5 59360.0 61155.4 75160.0 92921.0 78640.0 78877.8 79550.6 80688.0 81994.0 10950.4 11121.8 11228.6 11366.3 11394.3 39494.0 47436.8 47960.0 48864.0 49307.0 44507.7 63032.4 63156.0 63287.7 63389.4 12457.2 12865.9 13042.8 13512.5 13675.8 14799.1 14893.6 15738.5 16139.0 16311.3 13849.7 14506.4 14516.9 14833.4 14987.1 13887.1 14001.4 14312.5 14420.1 14963.9 13187.1 13224.5 13876.7 14022.4 14260.1 9613.4 9981.4 10599.2 10929.6 11009.9 7700.8 8951.7 9079.7 10652.8 11603.0 09:20:31 INFO - PROCESS | 1898 | -------- Summary: end -------- 09:20:31 INFO - PROCESS | 1898 | 09:20:32 INFO - TEST-INFO | 1898: exit 0 09:20:32 INFO - TEST-OK | dromaeo_css | took 1118322ms 09:20:32 INFO - TEST-START | kraken 09:20:32 INFO - operating with platform_type : mac_ 09:20:32 INFO - Initialising browser for kraken test... 09:20:32 INFO - TEST-INFO | started process 1903 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmphFT9jj/profile http://localhost:49344/getInfo.html) 09:20:34 INFO - PROCESS | 1903 | __metrics Screen width/height:1600/1200 09:20:34 INFO - PROCESS | 1903 | colorDepth:24 09:20:34 INFO - PROCESS | 1903 | Browser inner width/height: 1024/689 09:20:34 INFO - PROCESS | 1903 | __metrics 09:20:34 INFO - PROCESS | 1903 | 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" 09:20:34 INFO - PROCESS | 1903 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:20:35 INFO - TEST-INFO | 1903: exit 0 09:20:35 INFO - Browser initialized. 09:20:35 INFO - Running cycle 1/1 for kraken test... 09:20:35 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmphFT9jj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:47 INFO - PROCESS | 1905 | RSS: Main: 165183488 09:20:47 INFO - PROCESS | 1905 | 09:20:50 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49344/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:51 INFO - PROCESS | 1905 | RSS: Main: 216334336 09:20:51 INFO - PROCESS | 1905 | 09:20:53 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49344/tests/kraken/driver.html?testName=audio-dft) 09:20:54 INFO - PROCESS | 1905 | RSS: Main: 232525824 09:20:54 INFO - PROCESS | 1905 | 09:20:57 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49344/tests/kraken/driver.html?testName=audio-fft) 09:20:58 INFO - PROCESS | 1905 | RSS: Main: 328126464 09:20:58 INFO - PROCESS | 1905 | 09:21:00 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49344/tests/kraken/driver.html?testName=audio-oscillator) 09:21:00 INFO - PROCESS | 1905 | RSS: Main: 230195200 09:21:00 INFO - PROCESS | 1905 | 09:21:02 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49344/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:03 INFO - PROCESS | 1905 | RSS: Main: 231915520 09:21:03 INFO - PROCESS | 1905 | 09:21:06 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49344/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:07 INFO - PROCESS | 1905 | RSS: Main: 271876096 09:21:07 INFO - PROCESS | 1905 | 09:21:09 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49344/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:10 INFO - PROCESS | 1905 | RSS: Main: 272953344 09:21:10 INFO - PROCESS | 1905 | 09:21:13 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49344/tests/kraken/driver.html?testName=json-parse-financial) 09:21:14 INFO - PROCESS | 1905 | RSS: Main: 268943360 09:21:14 INFO - PROCESS | 1905 | 09:21:15 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49344/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:16 INFO - PROCESS | 1905 | RSS: Main: 247500800 09:21:16 INFO - PROCESS | 1905 | 09:21:18 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:18 INFO - PROCESS | 1905 | RSS: Main: 250912768 09:21:18 INFO - PROCESS | 1905 | 09:21:20 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:21 INFO - PROCESS | 1905 | RSS: Main: 256143360 09:21:21 INFO - PROCESS | 1905 | 09:21:24 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:21:25 INFO - PROCESS | 1905 | RSS: Main: 271192064 09:21:25 INFO - PROCESS | 1905 | 09:21:28 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:21:28 INFO - PROCESS | 1905 | RSS: Main: 264142848 09:21:28 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49344/tests/kraken/driver.html?testName=ai-astar) 09:21:30 INFO - PROCESS | 1905 | RSS: Main: 308969472 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | __start_tp_report 09:21:30 INFO - PROCESS | 1905 | _x_x_mozilla_page_load 09:21:30 INFO - PROCESS | 1905 | _x_x_mozilla_page_load_details 09:21:30 INFO - PROCESS | 1905 | |i|pagename|runs| 09:21:30 INFO - PROCESS | 1905 | |0;ai-astar;104;117;120;116;116;121;163;133;115;115 09:21:30 INFO - PROCESS | 1905 | |1;audio-beat-detection;137;109;107;143;135;130;109;108;127;107 09:21:30 INFO - PROCESS | 1905 | |2;audio-dft;185;186;183;199;259;184;186;184;183;181 09:21:30 INFO - PROCESS | 1905 | |3;audio-fft;79;59;64;112;85;81;59;59;73;58 09:21:30 INFO - PROCESS | 1905 | |4;audio-oscillator;88;70;86;68;68;68;82;68;70;84 09:21:30 INFO - PROCESS | 1905 | |5;imaging-gaussian-blur;87;86;87;86;87;86;87;86;86;86 09:21:30 INFO - PROCESS | 1905 | |6;imaging-darkroom;94;94;93;94;93;93;94;93;93;106 09:21:30 INFO - PROCESS | 1905 | |7;imaging-desaturate;84;84;84;84;85;84;85;86;83;87 09:21:30 INFO - PROCESS | 1905 | |8;json-parse-financial;59;58;59;61;62;62;61;62;63;63 09:21:30 INFO - PROCESS | 1905 | |9;json-stringify-tinderbox;53;59;61;65;59;59;56;58;59;58 09:21:30 INFO - PROCESS | 1905 | |10;stanford-crypto-aes;84;79;84;84;90;92;92;97;80;84 09:21:30 INFO - PROCESS | 1905 | |11;stanford-crypto-ccm;137;121;130;133;134;122;142;157;174;138 09:21:30 INFO - PROCESS | 1905 | |12;stanford-crypto-pbkdf2;163;211;165;161;163;185;204;144;208;215 09:21:30 INFO - PROCESS | 1905 | |13;stanford-crypto-sha256-iterative;64;62;66;63;64;63;62;64;62;63 09:21:30 INFO - PROCESS | 1905 | __end_tp_report 09:21:30 INFO - PROCESS | 1905 | __start_cc_report 09:21:30 INFO - PROCESS | 1905 | _x_x_mozilla_cycle_collect,2070 09:21:30 INFO - PROCESS | 1905 | __end_cc_report 09:21:30 INFO - PROCESS | 1905 | __startTimestamp1495210890846__endTimestamp 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | ------- Summary: start ------- 09:21:30 INFO - PROCESS | 1905 | Number of tests: 14 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#0] ai-astar Cycles:10 Average:122.00 Median:118.50 stddev:16.08 (13.6%) stddev-sans-first:15.68 09:21:30 INFO - PROCESS | 1905 | Values: 104.0 117.0 120.0 116.0 116.0 121.0 163.0 133.0 115.0 115.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:128.50 stddev:14.54 (11.3%) stddev-sans-first:14.25 09:21:30 INFO - PROCESS | 1905 | Values: 137.0 109.0 107.0 143.0 135.0 130.0 109.0 108.0 127.0 107.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#2] audio-dft Cycles:10 Average:193.00 Median:185.50 stddev:23.71 (12.8%) stddev-sans-first:24.97 09:21:30 INFO - PROCESS | 1905 | Values: 185.0 186.0 183.0 199.0 259.0 184.0 186.0 184.0 183.0 181.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#3] audio-fft Cycles:10 Average:72.90 Median:68.50 stddev:17.19 (25.1%) stddev-sans-first:18.09 09:21:30 INFO - PROCESS | 1905 | Values: 79.0 59.0 64.0 112.0 85.0 81.0 59.0 59.0 73.0 58.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:76.00 stddev:8.60 (11.3%) stddev-sans-first:7.77 09:21:30 INFO - PROCESS | 1905 | Values: 88.0 70.0 86.0 68.0 68.0 68.0 82.0 68.0 70.0 84.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:86.50 stddev:0.52 (0.6%) stddev-sans-first:0.50 09:21:30 INFO - PROCESS | 1905 | Values: 87.0 86.0 87.0 86.0 87.0 86.0 87.0 86.0 86.0 86.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:93.50 stddev:4.00 (4.3%) stddev-sans-first:4.24 09:21:30 INFO - PROCESS | 1905 | Values: 94.0 94.0 93.0 94.0 93.0 93.0 94.0 93.0 93.0 106.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.50 stddev:1.17 (1.4%) stddev-sans-first:1.22 09:21:30 INFO - PROCESS | 1905 | Values: 84.0 84.0 84.0 84.0 85.0 84.0 85.0 86.0 83.0 87.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:62.00 stddev:1.76 (2.8%) stddev-sans-first:1.72 09:21:30 INFO - PROCESS | 1905 | Values: 59.0 58.0 59.0 61.0 62.0 62.0 61.0 62.0 63.0 63.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:3.09 (5.2%) stddev-sans-first:2.50 09:21:30 INFO - PROCESS | 1905 | Values: 53.0 59.0 61.0 65.0 59.0 59.0 56.0 58.0 59.0 58.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:5.83 (6.7%) stddev-sans-first:6.11 09:21:30 INFO - PROCESS | 1905 | Values: 84.0 79.0 84.0 84.0 90.0 92.0 92.0 97.0 80.0 84.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:137.50 stddev:16.05 (11.7%) stddev-sans-first:17.01 09:21:30 INFO - PROCESS | 1905 | Values: 137.0 121.0 130.0 133.0 134.0 122.0 142.0 157.0 174.0 138.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:194.50 stddev:25.81 (13.3%) stddev-sans-first:26.45 09:21:30 INFO - PROCESS | 1905 | Values: 163.0 211.0 165.0 161.0 163.0 185.0 204.0 144.0 208.0 215.0 09:21:30 INFO - PROCESS | 1905 | 09:21:30 INFO - PROCESS | 1905 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:1.25 (2.0%) stddev-sans-first:1.30 09:21:30 INFO - PROCESS | 1905 | Values: 64.0 62.0 66.0 63.0 64.0 63.0 62.0 64.0 62.0 63.0 09:21:30 INFO - PROCESS | 1905 | -------- Summary: end -------- 09:21:30 INFO - PROCESS | 1905 | 09:21:31 INFO - TEST-INFO | 1905: exit 0 09:21:31 INFO - TEST-OK | kraken | took 59444ms 09:21:31 INFO - SUITE-END | took 1177s 09:21:32 INFO - Completed test suite (00:19:38) 09:21:32 INFO - javascript benchmark 09:21:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7894.452019767169, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3489.1277180704824, 3513.7189024878003, 3535.787852728835, 3575.070481674764, 3597.496350510929, 6126.509879209663, 6136.079588061793, 6164.969175154123, 6165.722542485588, 6221.25344958605, 6128.9387106128925, 6187.226596675416, 6204.193454850874, 6214.0, 6229.252489701231, 5490.533304669098, 5514.503694667479, 5559.527440167591, 5565.2486914266665, 5659.717014149276, 5438.211459338394, 5452.59105567082, 5482.862928426782, 5646.435356464362, 5667.489925906668, 5553.361363443195, 5558.305211848519, 5559.9444005560035, 5567.4432556744405, 5583.190437386576, 5612.663240205601, 5619.690916999563, 5625.6905870177125, 5639.379668236491, 5650.773969041234, 5520.239551934426, 5559.249501317311, 5573.554115670777, 5594.300712410949, 5594.440555944414, 5969.671668058299, 5977.671228082454, 6057.757689692451, 6220.813375598694, 6240.656763877985, 5697.715114244271, 6245.875082498324, 6344.968275158641, 6377.8405539861415, 6410.0, 6016.2479690038745, 6531.183602049744, 6536.444402225817, 6582.637954912478, 6586.901196482057, 5453.64551304164, 6243.906341404882, 6329.9872020476505, 6334.619922804646, 6345.07996340528, 6249.34381889895, 6537.738490460423, 6630.204375474974, 6658.833529161713, 6681.365270299314, 5405.918911216335, 6381.776637817654, 6409.935900640934, 6430.0676401921455, 6431.839204019937, 6477.546571739933, 6529.314421985718, 6564.573302735307, 6581.835454113685, 6583.440407565315, 6201.720922558496, 6247.031710084944, 6336.809895703136, 6361.650109244036, 6446.129772480655, 6208.441240288395, 6213.565050446426, 6277.748890044439, 6443.516736244706, 6471.0940468334475, 5208.5312321891215, 5213.296205012351, 5221.190715439113, 5238.371395432497, 5243.10867152585, 4670.579647831712, 4676.0, 4719.433667959798, 4726.432828060655, 4778.522147785194, 6199.442050215503, 6207.131001659771, 6219.0, 6219.191505104398, 6229.221347331583, 6277.435030847246, 6351.745930162834, 6476.190476190476, 6490.8052758417325, 6554.934450655528, 6237.594556353823, 6239.438450539473, 6244.344343843922, 6248.094026366242, 6262.530310226751, 5777.0, 5787.363390027093, 5796.82609521715, 5806.88386232273, 5843.123531470228, 5605.663660180402, 6471.288158302489, 6511.446527045065, 6650.434713049445, 6745.59526428406, 6529.8694026120165, 6599.076129341992, 6605.768798092236, 6608.471322294301, 6626.90059649096, 6768.0, 6768.4585233182215, 6780.69486873082, 6783.152105986752, 6803.863922721419, 6253.468455181361, 6311.842203944938, 6346.048092786119, 6376.011718183689, 6382.648954307558, 6485.416312531895, 6528.37980391862, 6542.0, 6545.836354091186, 6555.311692272434, 6264.31092579825, 6340.239171299289, 6349.682515874096, 6350.841228969312, 6375.904361434489, 5968.731407086605, 6254.968725156527, 6263.906041409291, 6269.185005949197, 6314.336994615499, 6272.843178920563, 6322.083297921684, 6330.651814150266, 6331.588446750947, 6346.492280617447, 6314.989601663712, 6346.777862774781, 6369.044643303541, 6370.522210834299, 6370.58591191571, 5470.945290547043, 5976.701164941648, 6214.782482613267, 6280.685965701605, 6322.146510221061], "value": 5996.51404229392, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.394419325023, 829.9751007469775, 845.1548451548451, 900.5227229568331, 910.6266430763388, 30324.241893952636, 43298.0, 43341.56658433415, 43424.565754342446, 43523.12953740923, 43621.7818910905, 43898.78050609743, 43907.3413898791, 43949.0, 44197.337039944425, 8004.159563245863, 8119.309858662021, 9653.179479744213, 9834.164096051809, 9839.852402213972, 11937.164398492108, 12018.759624807499, 12280.078994075435, 12306.81539776904, 12310.507579696847, 12124.818127728091, 12125.939370303136, 12137.514499420013, 12161.027117830596, 12163.331016794073, 12070.155089143764, 12071.698207544794, 12112.454939527743, 12112.6366209013, 12155.331456769874, 11722.824157637642, 11760.411979400995, 11781.469833857496, 11788.997935175521, 11793.29240245588, 41304.79347603243, 41357.586424135676, 41431.75704728863, 41445.37831932523, 41499.1700165998, 3567.7680950738118, 3985.362342025291, 4101.4668093148, 4124.381342798574, 4137.193247316774, 28489.430211395862, 28882.566761498594, 30135.397292054255, 30955.380892382025, 30967.0709878704, 2925.7220564046593, 3181.9363612727616, 3312.685294896981, 3353.6478669739813, 3442.621311655716, 360498.0, 361249.0, 361271.0, 361635.19182404183, 362088.75822483405, 54018.0, 54157.91684166295, 54189.0, 54320.0, 54350.45649543533, 56496.0, 56618.716906416026, 56684.43315566792, 56688.43311566914, 56739.0, 15440.84559154394, 15543.378264869278, 15554.844451555566, 15559.533214003599, 15577.454789082327, 11927.88072119285, 12078.396080195955, 13222.735545289233, 13239.73520529584, 13250.939924805993, 12620.242785432903, 12834.358282086045, 13042.282674452807, 13047.543335983195, 13049.934750326187, 5613.46672066153, 5829.300483941955, 5843.883122337529, 5848.239728835224, 5857.297124345021, 980.2794945714894, 1139.7378602921208, 1146.8337091121741, 1191.845060142176, 1212.9575464858688, 23599.29202123939, 28028.159155224967, 28178.591070446397, 28714.85642571774, 28787.28031799222, 972.4602845420718, 1037.0459177556666, 1082.2532452607675, 1161.8199179127248, 1334.2594859852682, 15146.394144234328, 15310.617234569225, 15439.30523126462, 15820.525384238492, 16114.952528085636, 481094.0, 485962.0, 486743.0, 487053.0, 488126.1187388152, 47808.04383912302, 59623.0, 59762.0, 59787.40212597905, 59792.40207597955, 1466.0, 1502.3164460170378, 1504.7216264991011, 1513.1526345246698, 1515.6589767302269, 25368.873155634104, 29896.50517474161, 31859.681403186598, 32029.71881124822, 32472.350552988337, 49719.25421118759, 49805.0, 49935.500644994536, 49975.500244997085, 50039.74900627513, 470542.7092967973, 476653.0, 477373.0, 478150.0, 478742.0, 31128.68871311258, 33811.0, 34397.140071497415, 34424.31151377009, 34513.48229776606, 46288.30567541557, 46895.531044689116, 47478.52521474741, 47883.0, 48174.5182548184, 31064.844675776476, 31779.36441271208, 31879.52180717245, 32556.837215814718, 33010.66989330076, 31281.217969550034, 31389.68610313959, 31454.0, 31772.523412148374, 32407.51388729218, 36592.45111323279, 37582.624173759, 37679.24641507209, 37985.4302185473, 38376.6162338373, 25061.498770024133, 31933.68066319307, 32679.0, 32696.34607307888, 32940.50589241115, 23758.762412375654, 24539.141130061078, 24731.876340618182, 25170.74829251684, 25486.490270194863], "value": 19915.43274725723, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.37900838246816, 433.7010653093984, 441.41071669321445, 446.718205995533, 451.6973627669463, 20888.89555552222, 21979.78020219799, 22186.0, 23025.194118205865, 23178.42053948652, 22446.663300050513, 23372.948217330217, 23520.411989700267, 23548.64677029846, 23828.927698253578, 6838.555493892906, 6899.37905588497, 6906.0, 7036.261192574782, 7042.6126563039015, 6632.436242919358, 6947.617881016542, 6951.409130223937, 6961.895571566431, 6963.408110310605, 6601.438877695402, 6862.107925969642, 6916.066331045295, 6945.131858517685, 6947.513674042793, 6777.525573209878, 6849.760258390932, 6876.828079298008, 6935.791926242196, 6943.618101004443, 6718.563293385914, 6720.126383570154, 6733.360330768618, 6735.292794256581, 6737.966310168466, 27252.59121113185, 27638.309042274104, 27677.86161069202, 27698.5845212322, 27713.168604941882, 2928.633920759905, 3022.1689035515187, 3026.228311780504, 3055.4500189965797, 3098.721115099652, 16337.673246535122, 16509.83490165107, 17012.574685632957, 17048.82951170497, 17831.554211144696, 1875.4279944616887, 1938.7673479182588, 1951.5023668964466, 1986.3345779163997, 1990.7212990181386, 116571.0, 120042.3997880005, 120112.0, 120173.0, 120494.0, 19181.424557263304, 19932.402027939184, 20062.0, 20261.392158235278, 21469.892650536647, 31285.687143128733, 31707.890223842056, 31748.523772143435, 31841.363172736415, 31990.84004579962, 10225.233107516966, 10450.477476126316, 10607.84088238677, 10609.787804244026, 10801.379793031108, 8944.418612790278, 8952.373333866697, 8967.686130985385, 9137.360384773003, 9148.039455857039, 9300.674476393293, 9365.765855853659, 9437.764055898657, 9451.669191578261, 9487.620495180116, 3379.391709492315, 3883.4757307763657, 3917.845532900544, 3944.5660977292473, 3951.7233793634746, 1054.283087500507, 1094.1520321750706, 1095.687728997232, 1108.6031600183826, 1151.7523732397574, 22159.12215362305, 22414.0, 22447.0, 22469.88765056197, 22624.208152714575, 1020.1175982774846, 1084.9077828384518, 1100.884407137255, 1100.9669709908715, 1153.6885041038872, 11188.328700278009, 11634.476448559835, 11675.104072443532, 11748.706282342839, 11792.23350482216, 747040.0, 759843.0, 775382.0, 800359.0, 819830.0, 152537.0, 157889.0, 160870.0, 161715.0, 164381.0, 1268.4355461819403, 1295.1387327427155, 1297.5718013055669, 1326.562234462625, 1327.5751759436887, 18848.717269240697, 19892.403227903185, 19895.303664371677, 19943.401697949084, 19977.90121543271, 65860.0120998195, 66455.66772166108, 66825.66587167032, 68349.0, 68721.65639171773, 94227.52886235525, 95325.5233723827, 95640.5653915178, 96431.11246000728, 97353.0, 10447.634332798316, 11039.944800275947, 11103.38931358783, 11446.37044962535, 11564.94217528907, 12832.973362131193, 12876.871231287567, 12965.286909220084, 13040.152390094614, 13421.127626704232, 11237.693951314883, 11738.41307934617, 11822.995045420892, 11825.763484730429, 11831.645050648494, 10281.74295642615, 10289.588416463257, 10528.736781580521, 10570.577176912837, 10748.570057197625, 9555.761105972406, 11564.94217528907, 11625.709357265798, 11700.239484433485, 11802.94098529502, 11620.593279235525, 11625.0, 11654.658446606682, 11864.0, 11900.821487677866, 10205.642802501878, 10944.945275273572, 11220.943895280472, 11728.237664551776, 11743.236689615147], "value": 11828.146039936799, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.6814716688739, 1613.1934032983502, 1638.9508314750562, 1647.5716313758426, 1652.9173541322932, 9438.952805235973, 9861.802763944726, 9871.703848884526, 9908.603655853758, 9908.702738917826, 9774.218062554995, 10954.178436617245, 11215.775684486325, 11454.0, 11456.770864582704, 2465.2357769091595, 2511.309389917769, 2514.446821699223, 2514.5976643737004, 2515.71069327027, 2060.917563297466, 2368.549975504657, 2491.638712386703, 2494.638277449769, 2497.325722055044, 2152.9031193596325, 2170.1536400803698, 2174.5976994256034, 2178.2593918067855, 2178.814800741939, 2227.498812767131, 2293.5068960173558, 2300.0454811253308, 2302.6200676888293, 2306.7693230676964, 2417.782399584028, 2445.0097710427303, 2447.9510409791706, 2449.2652204338806, 2452.1907770435714, 11258.155638327158, 11516.30902145874, 11528.481218345194, 11554.364509951949, 11577.247478913843, 5929.73316200772, 6107.144999700045, 6111.480524155452, 6111.816645500642, 6112.541559383064, 3455.4989526518516, 3771.7359784815076, 3820.1977584707247, 3842.9615703842883, 3886.8833934981994, 1282.9743405131937, 1737.6785294720517, 1892.167446323627, 1925.6341295153875, 1927.787943326233, 15922.76115858263, 16075.678486430323, 16108.516744497683, 16114.355425783071, 16130.354785808553, 4067.8372865085066, 4687.086018226413, 4833.564979151894, 4886.755662216875, 4927.187014142698, 11738.119641026957, 11756.177067605357, 11764.176507644554, 11793.351365674798, 11815.2320099195, 6020.69896505173, 6022.247219097613, 6026.819195424145, 6032.547558933097, 6041.18444010053, 4602.654800889946, 5249.133892907704, 5415.918761218585, 5497.45025497447, 5510.118381059011, 3864.14988702485, 3948.881533553998, 3976.5426975897562, 4015.0, 4031.294523458383, 943.1841457139525, 1052.0373857919917, 1064.2656566968922, 1067.215596536545, 1083.3012706804225, 921.6129225725211, 935.4621092871625, 944.9291303152291, 946.8153710026479, 965.2374624046925, 6333.746650134035, 6452.35476452351, 6499.805005849832, 6581.440577550962, 6804.897926531106, 922.276013329533, 974.2887691984749, 1039.7660526381503, 1045.6078970386104, 1054.8681414823147, 3952.664023557972, 3994.5206575210577, 4122.958770412318, 4152.896177595524, 4155.646770024582, 13824.239666818421, 14319.21244331551, 14336.56990290293, 14341.067830590979, 14360.425582976774, 14239.0, 14243.85756142446, 14253.0, 14253.501127460697, 14266.215358155194, 9996.800063998824, 10303.3817970922, 10470.424126673106, 10514.26400151982, 10525.89474105249, 5044.8991020178655, 6017.488513476404, 6398.200224971879, 6450.612963222222, 6531.11829902976, 12216.75566488683, 12455.688607784878, 12657.303848288064, 12721.681957951125, 12766.29785361814, 10927.180461465581, 12647.0, 13034.47862085506, 13051.21692698441, 13065.281409522187, 8413.579321033802, 8763.211310982042, 8780.297576194016, 8780.385373023826, 8784.253338466302, 10127.442990635585, 10339.741506462398, 10418.374897506174, 10439.269251152346, 10479.79040419172, 8724.345674074342, 8914.955425222832, 8996.0, 9056.338446607637, 9107.817843643223, 8661.566921654017, 8669.046404895582, 8718.607662655322, 8736.951565812144, 8793.956030219808, 7848.84302313962, 8005.599720014093, 8018.4787988780545, 8043.235892590194, 8048.275655191061, 5053.646244762831, 5682.715864206856, 5711.400302968294, 5803.564732645153, 5924.555658325557, 5305.920411193758, 5761.827145185651, 5785.739641716049, 5861.589688721749, 5980.641161530322], "value": 5204.280701027048, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.36310358770584, 394.5108065998163, 400.1816285695752, 400.82163437270407, 401.8633664554051, 4447.555244475551, 4611.031683346497, 5146.228065790134, 5166.339335242354, 5282.973585132078, 4435.201663700533, 4848.2242841145335, 5031.34592502975, 5177.223416487529, 5178.5339319461245, 3419.8543487931574, 4279.786010699452, 4312.568743125678, 4331.43691320128, 4340.609345158927, 4048.6153815387493, 4150.983009162759, 4345.413369195166, 4351.844912438489, 4356.695031347807, 3631.7858695054933, 3857.189990102082, 3895.7467764595353, 4334.84828031019, 4357.891052723676, 3491.5810102787573, 3715.9628403716156, 4055.655269302113, 4196.97901510492, 4215.9670880634285, 3415.8804441844663, 3428.0058782952915, 3873.0704630888667, 3964.147708242742, 3976.324024915771, 4246.851360202409, 5027.019731152427, 5057.508035129646, 5119.365250672027, 5193.792248310063, 4126.896827579335, 4563.178627846986, 4933.80264789408, 5014.423341315759, 5050.4848545436225, 4974.9751251243515, 5543.918935807519, 5573.804916827932, 5599.720013999325, 5601.935632229904, 4048.7246517347235, 4206.768627725474, 4221.641160501361, 4278.657707383401, 4365.825366985317, 5059.418166910816, 5648.181013753024, 5746.793173433584, 5886.9705651471895, 5900.409959004108, 4479.664025198058, 4623.884402889955, 4628.652851036186, 4636.304554316879, 4669.813207471731, 4101.498086167495, 4137.855175068858, 4669.4396672399525, 4730.739809310452, 4775.379200703956, 4199.349100889367, 4554.339620754972, 4943.8516844494725, 4969.329140566049, 5022.799088036438, 5408.8377348679605, 5423.755930983115, 5436.91844622331, 5454.963556924251, 5486.533644640251, 1420.5809286260578, 1475.5352064099775, 1494.8206215254238, 1604.278074866315, 1620.3518592563069, 1041.6250549275035, 1072.8980746829038, 1100.8183649697871, 1111.9777604447866, 1132.133917553056, 4754.286856971482, 4820.855374338776, 4981.526754958273, 5236.214567814858, 5273.288106105703, 4711.882202944886, 5257.083472812779, 5634.774609015675, 5658.830235092953, 5669.659820410789, 4659.254519276899, 4750.648429027653, 5171.362605071441, 5172.612054095958, 5235.895282094337, 2805.957910631342, 2891.7686585073143, 2948.9705102949124, 2985.3730716549335, 3005.5191169412788, 5517.420670829585, 5553.750730953971, 5882.264716910387, 5911.881762364728, 5928.407159284124, 4568.223402021665, 4799.904001919942, 5126.641135120506, 5132.589392848563, 5497.3207552823515, 4997.575206107449, 5076.238564215323, 5113.693178409308, 5664.631798933056, 5806.0, 4827.08285425768, 4979.103761322996, 5193.818216362409, 5374.167004114369, 5684.459976302244, 5088.465711100281, 5143.897122057613, 5567.972160139173, 5664.697119662418, 5708.914366284426, 5274.472552744694, 5327.467253274642, 5527.351947714489, 5773.04744717117, 5812.622179558315, 4334.869953901388, 4843.0, 5036.471170527042, 5078.339815823919, 5131.0, 5116.872078198075, 5239.633225674166, 5409.756560954846, 5791.276090488689, 5838.678872661874, 5268.57851371897, 5442.891142177214, 5472.288602481652, 5612.678237756986, 5637.464440878274, 4805.519448055166, 4863.586595139312, 5336.852576695874, 5529.364123125892, 5575.526080283059, 4342.544032876503, 4559.338895860148, 4776.713397196179, 5390.272313237662, 5526.728852363898, 4962.330085438564, 5097.337346145126, 5268.76290566933, 5670.971645141748, 5708.80019199326, 4690.859274221779, 4969.0, 5039.546440820343, 5319.867003324948, 5573.6098473106495], "value": 4197.579240834922, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.4515593928311, 780.8477346917347, 886.3042511628369, 886.6763631274584, 910.244497067434, 15799.368025278989, 16702.83297167028, 17428.215730292137, 18095.4571362859, 18101.637967240647, 16131.83868161318, 16142.7578586321, 16184.676306473864, 16555.91722041391, 16636.25136868841, 848.5714714069386, 1007.3905287301187, 1012.5443550402312, 1016.7102375822892, 1019.8011387779404, 999.4852650884764, 1006.5118417567473, 1023.9606799098921, 1026.6765968719867, 1032.9122024627916, 1073.8281874900017, 1074.3339129739572, 1074.8602681651375, 1075.9462026898664, 1076.7146706122844, 1066.8186408310605, 1068.1561566362564, 1068.2362111090608, 1068.9465526723632, 1068.9679309620724, 793.317746737805, 799.8120441696196, 800.5756948817136, 802.7671975127206, 804.8229389534293, 13180.802287965687, 17189.22648480809, 17246.482605521855, 17408.042557659322, 17725.93644381341, 14642.633934151732, 14741.0, 14816.259187040605, 14857.925710371486, 14874.0, 12192.57325993586, 12295.4467048983, 12296.60911687989, 12322.507099716, 12614.180078294881, 3815.618438156162, 3819.1597848473293, 3842.3083844907906, 3932.7247092703524, 3987.182627561343, 92501.0, 97670.69869104082, 106143.46928265387, 106182.0, 107001.46499267532, 11133.554657813758, 11220.551177952953, 11227.0, 11249.83125253122, 11496.42517874103, 22663.433414164447, 22675.0, 22685.319440416813, 22954.19660311881, 23022.07911683514, 7458.216887226871, 7746.922530774733, 7788.610569471503, 8000.5599692017495, 8039.758807235792, 4565.954340456553, 4574.931376029363, 4614.377059097046, 4643.6517261205545, 4780.4741478437345, 3073.6772638873044, 3697.9445308320396, 3722.181120153562, 3729.0863738383728, 3741.0273328934127, 655.452696998012, 656.1994366872408, 662.7912207654573, 672.5930811858852, 675.3347952267013, 685.8491131950963, 706.494856177825, 709.6558169287891, 728.5373787644778, 759.9240075992357, 8576.44253123545, 10613.363198208132, 10916.290441121302, 10955.83566246507, 11038.503267352988, 672.6132473827569, 699.9650017499105, 713.9071920650282, 717.4188906985254, 734.522560335786, 4035.3946907963455, 5038.118329292359, 5055.469175736568, 5441.945580544223, 5649.4915457608195, 92034.53982730178, 99106.51340229902, 106337.97542305586, 108237.458812707, 110708.0, 52580.47419525832, 59360.0, 61155.38844611497, 75160.0, 92921.0, 78640.0, 78877.81683274756, 79550.6022469907, 80687.99999999882, 81994.0, 10950.39772812503, 11121.83317250258, 11228.550857965916, 11366.318020918772, 11394.25937314072, 39494.0, 47436.76281618708, 47960.0, 48864.0227195447, 49307.0, 44507.66192535508, 63032.369676304486, 63156.0, 63287.6835615819, 63389.36610633835, 12457.183705483565, 12865.935670321589, 13042.804357934829, 13512.459501619825, 13675.79486307726, 14799.112053276838, 14893.627659308604, 15738.527844164693, 16139.031658100552, 16311.265993030105, 13849.723005539632, 14506.419743210154, 14516.854831451972, 14833.406663733762, 14987.100773953598, 13887.097338672955, 14001.439942402598, 14312.499062532761, 14420.062695924731, 14963.925180374028, 13187.076904616968, 13224.537141200011, 13876.722465550834, 14022.368993395527, 14260.073095248776, 9613.375130616487, 9981.401115933068, 10599.205059620714, 10929.56281748753, 11009.889901100887, 7700.845983080195, 8951.686690965786, 9079.727608171765, 10652.84020739674, 11603.0], "value": 7843.752794921059, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1440.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 120.0, 116.0, 116.0, 121.0, 163.0, 133.0, 115.0, 115.0], "value": 122.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 109.0, 107.0, 143.0, 135.0, 130.0, 109.0, 108.0, 127.0, 107.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 183.0, 199.0, 259.0, 184.0, 186.0, 184.0, 183.0, 181.0], "value": 193.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 64.0, 112.0, 85.0, 81.0, 59.0, 59.0, 73.0, 58.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 86.0, 68.0, 68.0, 68.0, 82.0, 68.0, 70.0, 84.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 86.0, 87.0, 86.0, 87.0, 86.0, 86.0, 86.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 106.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 85.0, 86.0, 83.0, 87.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 59.0, 61.0, 62.0, 62.0, 61.0, 62.0, 63.0, 63.0], "value": 61.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 59.0, 61.0, 65.0, 59.0, 59.0, 56.0, 58.0, 59.0, 58.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 84.0, 84.0, 90.0, 92.0, 92.0, 97.0, 80.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 121.0, 130.0, 133.0, 134.0, 122.0, 142.0, 157.0, 174.0, 138.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 211.0, 165.0, 161.0, 163.0, 185.0, 204.0, 144.0, 208.0, 215.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 66.0, 63.0, 64.0, 63.0, 62.0, 64.0, 62.0, 63.0], "value": 63.3, "unit": "score"}]}]} 09:21:32 INFO - Return code: 0 09:21:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:21:32 INFO - # TBPL SUCCESS # 09:21:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:21:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:21:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:21:32 INFO - 2017-05-19 09:21:32.156 screenresolution[1909:10670] starting screenresolution argv=screenresolution get 09:21:32 INFO - 2017-05-19 09:21:32.159 screenresolution[1909:10670] Display 0: 1600x1200x32@60 09:21:32 INFO - 2017-05-19 09:21:32.164 screenresolution[1910:10673] starting screenresolution argv=screenresolution list 09:21:32 INFO - Available Modes on Display 0 09:21:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:21:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:21:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:21:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:21:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:21:32 INFO - Intel Iris: 09:21:32 INFO - Chipset Model: Intel Iris 09:21:32 INFO - Type: GPU 09:21:32 INFO - Bus: Built-In 09:21:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:21:32 INFO - Vendor: Intel (0x8086) 09:21:32 INFO - Device ID: 0x0a2e 09:21:32 INFO - Revision ID: 0x0009 09:21:32 INFO - Displays: 09:21:32 INFO - Display: 09:21:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:21:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:21:32 INFO - Display Serial Number: 1600x1200 60 09:21:32 INFO - Main Display: Yes 09:21:32 INFO - Mirror: Off 09:21:32 INFO - Online: Yes 09:21:32 INFO - Rotation: Supported 09:21:32 INFO - Return code: 0 09:21:32 INFO - Running post-action listener: _resource_record_post_action 09:21:32 INFO - [mozharness: 2017-05-19 16:21:32.399322Z] Finished run-tests step (success) 09:21:32 INFO - Running post-run listener: _resource_record_post_run 09:21:32 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 2248704; Write bytes: 419022848; Read time: 21; Write time: 1490 09:21:32 INFO - TinderboxPrint: CPU usage
53.9% 09:21:32 INFO - TinderboxPrint: I/O read bytes / time
2,248,704 / 21 09:21:32 INFO - TinderboxPrint: I/O write bytes / time
419,022,848 / 1,490 09:21:32 INFO - TinderboxPrint: CPU idle
2,202.2 (46.1%) 09:21:32 INFO - TinderboxPrint: CPU system
956.2 (20.0%) 09:21:32 INFO - TinderboxPrint: CPU user
1,615.4 (33.8%) 09:21:32 INFO - TinderboxPrint: Swap in / out
124,628,992 / 0 09:21:32 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 108413440; Write bytes: 103958528; Read time: 9823; Write time: 227 09:21:32 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 2248704; Write bytes: 294227968; Read time: 21; Write time: 1177 09:21:32 INFO - Running post-run listener: _upload_blobber_files 09:21:32 INFO - Blob upload gear active. 09:21:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:21:32 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 09:21:32 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'] 09:21:32 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 09:21:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:21:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:21:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:21:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:34 INFO - (blobuploader) - INFO - Done attempting. 09:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:21:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:35 INFO - (blobuploader) - INFO - Done attempting. 09:21:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:21:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:21:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:36 INFO - (blobuploader) - INFO - Done attempting. 09:21:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:21:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:21:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:38 INFO - (blobuploader) - INFO - Done attempting. 09:21:38 INFO - (blobuploader) - INFO - Iteration through files over. 09:21:38 INFO - Return code: 0 09:21:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:21:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:21:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/56f273d3670cb5fff69c4ca96ae76a490f396844a6646571dad1c22b4e547284387ace84641ef127d0775e38f840169f9b040602a2a0c9a52a1696110dfe9cb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6714751c13faeaa92fcea4eeda4c9605b34bacbce4f448acf278ecb272b6392bb2e7e16aa7d9ad9ef294b2d917b7855f780eb9cf9969a8114815b102f3325c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c17acd4b14f8f0445d3b10bfe270d2ccaa3b36caa80387ed32933355362bbe8852d8cee6babfb2dcd7e8ee2ff0ce24b1c458f85554670d2414b9a605e4acfd10", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d329d7c0ecd13ee11f696064cd7d08f26a8753611f883dc9dc2307063944aa6333fecf758e02028ef6958f6b62e8a1a16f7288f2476bd68bd9bccb634d759e9"} 09:21:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:21:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:21:38 INFO - Contents: 09:21:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/56f273d3670cb5fff69c4ca96ae76a490f396844a6646571dad1c22b4e547284387ace84641ef127d0775e38f840169f9b040602a2a0c9a52a1696110dfe9cb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6714751c13faeaa92fcea4eeda4c9605b34bacbce4f448acf278ecb272b6392bb2e7e16aa7d9ad9ef294b2d917b7855f780eb9cf9969a8114815b102f3325c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c17acd4b14f8f0445d3b10bfe270d2ccaa3b36caa80387ed32933355362bbe8852d8cee6babfb2dcd7e8ee2ff0ce24b1c458f85554670d2414b9a605e4acfd10", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d329d7c0ecd13ee11f696064cd7d08f26a8753611f883dc9dc2307063944aa6333fecf758e02028ef6958f6b62e8a1a16f7288f2476bd68bd9bccb634d759e9"} 09:21:38 INFO - Running post-run listener: copy_logs_to_upload_dir 09:21:38 INFO - Copying logs to upload dir... 09:21:38 INFO - mkdir: /builds/slave/test/build/upload/logs 09:21:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.155810 ========= master_lag: 1.24 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-19 09:21:39.781335) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 09:21:39.784437) ========= 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.g682vTr1P5/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.MMi8TvTjpE/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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/56f273d3670cb5fff69c4ca96ae76a490f396844a6646571dad1c22b4e547284387ace84641ef127d0775e38f840169f9b040602a2a0c9a52a1696110dfe9cb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6714751c13faeaa92fcea4eeda4c9605b34bacbce4f448acf278ecb272b6392bb2e7e16aa7d9ad9ef294b2d917b7855f780eb9cf9969a8114815b102f3325c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c17acd4b14f8f0445d3b10bfe270d2ccaa3b36caa80387ed32933355362bbe8852d8cee6babfb2dcd7e8ee2ff0ce24b1c458f85554670d2414b9a605e4acfd10", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d329d7c0ecd13ee11f696064cd7d08f26a8753611f883dc9dc2307063944aa6333fecf758e02028ef6958f6b62e8a1a16f7288f2476bd68bd9bccb634d759e9"} build_url:https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011151 build_url: 'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/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/56f273d3670cb5fff69c4ca96ae76a490f396844a6646571dad1c22b4e547284387ace84641ef127d0775e38f840169f9b040602a2a0c9a52a1696110dfe9cb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6714751c13faeaa92fcea4eeda4c9605b34bacbce4f448acf278ecb272b6392bb2e7e16aa7d9ad9ef294b2d917b7855f780eb9cf9969a8114815b102f3325c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c17acd4b14f8f0445d3b10bfe270d2ccaa3b36caa80387ed32933355362bbe8852d8cee6babfb2dcd7e8ee2ff0ce24b1c458f85554670d2414b9a605e4acfd10", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d329d7c0ecd13ee11f696064cd7d08f26a8753611f883dc9dc2307063944aa6333fecf758e02028ef6958f6b62e8a1a16f7288f2476bd68bd9bccb634d759e9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 09:21:39.826740) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:21:39.827073) ========= 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.g682vTr1P5/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.MMi8TvTjpE/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.005075 ========= master_lag: 0.14 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:21:39.970757) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 09:21:39.975102) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 09:21:39.975560) ========= ========= Total master_lag: 1.50 =========