builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0088 starttime: 1495637318.67 results: success (0) buildid: 20170524055525 builduid: 4ea5015101734f388cb31d278689bad1 revision: 7166b44658df0723b16d43b7f884deb73c99f0ba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:38.669794) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:38.670238) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:38.670552) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:38.696147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:38.696443) ========= 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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-24 07:48:38-- 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% 107M=0s 2017-05-24 07:48:38 (107 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153006 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:38.884141) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:38.884459) ========= 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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.028400 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:38.937481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 07:48:38.937836) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7166b44658df0723b16d43b7f884deb73c99f0ba --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 7166b44658df0723b16d43b7f884deb73c99f0ba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LhuLIRS8x9/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.BWnrO8YoEb/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-24 07:48:39,031 truncating revision to first 12 chars 2017-05-24 07:48:39,031 Setting DEBUG logging. 2017-05-24 07:48:39,031 attempt 1/10 2017-05-24 07:48:39,031 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7166b44658df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 07:48:40,527 unpacking tar archive at: mozilla-beta-7166b44658df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.761933 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 07:48:40.722927) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:40.723299) ========= script_repo_revision: 7166b44658df0723b16d43b7f884deb73c99f0ba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:40.723732) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:40.724098) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:40.743370) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-24 07:48:40.743740) ========= /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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:48:40 INFO - MultiFileLogger online at 20170524 07:48:40 in /builds/slave/test 07:48:40 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 07:48:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:48:40 INFO - {'append_to_log': False, 07:48:40 INFO - 'base_work_dir': '/builds/slave/test', 07:48:40 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:48:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:48:40 INFO - 'branch': 'Mozilla-Beta', 07:48:40 INFO - 'buildbot_json_path': 'buildprops.json', 07:48:40 INFO - 'config_files': ('talos/mac_config.py',), 07:48:40 INFO - 'default_actions': ('clobber', 07:48:40 INFO - 'read-buildbot-config', 07:48:40 INFO - 'download-and-extract', 07:48:40 INFO - 'populate-webroot', 07:48:40 INFO - 'create-virtualenv', 07:48:40 INFO - 'install', 07:48:40 INFO - 'run-tests'), 07:48:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:48:40 INFO - 'download_minidump_stackwalk': True, 07:48:40 INFO - 'download_symbols': 'ondemand', 07:48:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:48:40 INFO - 'tooltool.py': '/tools/tooltool.py', 07:48:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:48:40 INFO - '/tools/misc-python/virtualenv.py')}, 07:48:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:48:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:48:40 INFO - 'gecko_profile': False, 07:48:40 INFO - 'gecko_profile_interval': 0, 07:48:40 INFO - 'installer_path': 'installer.exe', 07:48:40 INFO - 'log_level': 'info', 07:48:40 INFO - 'log_name': 'talos', 07:48:40 INFO - 'log_to_console': True, 07:48:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:48:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:48:40 INFO - 'opt_config_files': (), 07:48:40 INFO - 'pip_index': False, 07:48:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:48:40 INFO - 'cmd': ('bash', 07:48:40 INFO - '-c', 07:48:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:48:40 INFO - 'enabled': True, 07:48:40 INFO - 'halt_on_failure': False, 07:48:40 INFO - 'name': 'check_screen_resolution'},), 07:48:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:48:40 INFO - 'cmd': ('bash', 07:48:40 INFO - '-c', 07:48:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:48:40 INFO - 'enabled': True, 07:48:40 INFO - 'halt_on_failure': False, 07:48:40 INFO - 'name': 'check_screen_resolution'},), 07:48:40 INFO - 'run_cmd_checks_enabled': True, 07:48:40 INFO - 'suite': 'dromaeojs', 07:48:40 INFO - 'system_bits': '32', 07:48:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:48:40 INFO - 'title': 't-yosemite-r7-0088', 07:48:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:48:40 INFO - 'use_talos_json': True, 07:48:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:48:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:48:40 INFO - 'work_dir': 'build'} 07:48:40 INFO - [mozharness: 2017-05-24 14:48:40.945618Z] Running clobber step. 07:48:40 INFO - Running pre-action listener: _resource_record_pre_action 07:48:40 INFO - Running main action method: clobber 07:48:40 INFO - rmtree: /builds/slave/test/build 07:48:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:48:43 INFO - Running post-action listener: _resource_record_post_action 07:48:43 INFO - [mozharness: 2017-05-24 14:48:43.419459Z] Finished clobber step (success) 07:48:43 INFO - [mozharness: 2017-05-24 14:48:43.419582Z] Running read-buildbot-config step. 07:48:43 INFO - Running pre-action listener: _resource_record_pre_action 07:48:43 INFO - Running main action method: read_buildbot_config 07:48:43 INFO - Using buildbot properties: 07:48:43 INFO - { 07:48:43 INFO - "project": "", 07:48:43 INFO - "product": "firefox", 07:48:43 INFO - "script_repo_revision": "production", 07:48:43 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 07:48:43 INFO - "repository": "", 07:48:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 07:48:43 INFO - "buildid": "20170524055525", 07:48:43 INFO - "pgo_build": "False", 07:48:43 INFO - "basedir": "/builds/slave/test", 07:48:43 INFO - "buildnumber": 0, 07:48:43 INFO - "slavename": "t-yosemite-r7-0088", 07:48:43 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:48:43 INFO - "platform": "yosemite_r7_devedition", 07:48:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 07:48:43 INFO - "branch": "mozilla-beta", 07:48:43 INFO - "repo_path": "releases/mozilla-beta", 07:48:43 INFO - "revision": "7166b44658df0723b16d43b7f884deb73c99f0ba", 07:48:43 INFO - "stage_platform": "macosx64-devedition", 07:48:43 INFO - "builduid": "4ea5015101734f388cb31d278689bad1", 07:48:43 INFO - "slavebuilddir": "test" 07:48:43 INFO - } 07:48:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 07:48:43 INFO - Running post-action listener: _resource_record_post_action 07:48:43 INFO - [mozharness: 2017-05-24 14:48:43.422941Z] Finished read-buildbot-config step (success) 07:48:43 INFO - [mozharness: 2017-05-24 14:48:43.423072Z] Running download-and-extract step. 07:48:43 INFO - Running pre-action listener: _resource_record_pre_action 07:48:43 INFO - Running main action method: download_and_extract 07:48:43 INFO - mkdir: /builds/slave/test/build/tests 07:48:43 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')]} 07:48:43 INFO - trying https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 07:48:43 INFO - Downloading https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/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 07:48:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/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 07:48:44 INFO - Downloaded 1287 bytes. 07:48:44 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:48:44 INFO - Using the following test package requirements: 07:48:44 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:44 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 07:48:44 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:48:44 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:44 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 07:48:44 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:44 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 07:48:44 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:48:44 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:44 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 07:48:44 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:48:44 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:44 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 07:48:44 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:44 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 07:48:44 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:44 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 07:48:44 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:44 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 07:48:44 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 07:48:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:48:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:48:44 INFO - Fetch https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:48:46 INFO - Expected file size: 51964669 07:48:46 INFO - Obtained file size: 51964669 07:49:02 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 07:49:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:49:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:49:02 INFO - Fetch https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:49:04 INFO - Expected file size: 51964669 07:49:04 INFO - Obtained file size: 51964669 07:49:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 07:49:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 07:49:19 INFO - Fetch https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 07:49:20 INFO - Expected file size: 14404449 07:49:20 INFO - Obtained file size: 14404449 07:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:20 INFO - trying https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:49:20 INFO - Downloading https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 07:49:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/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 07:49:22 INFO - Downloaded 74891043 bytes. 07:49:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:49:22 INFO - mkdir: /builds/slave/test/properties 07:49:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:49:22 INFO - Writing to file /builds/slave/test/properties/build_url 07:49:22 INFO - Contents: 07:49:22 INFO - build_url:https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:49:23 INFO - Running post-action listener: _resource_record_post_action 07:49:23 INFO - Running post-action listener: set_extra_try_arguments 07:49:23 INFO - [mozharness: 2017-05-24 14:49:23.653301Z] Finished download-and-extract step (success) 07:49:23 INFO - [mozharness: 2017-05-24 14:49:23.653453Z] Running populate-webroot step. 07:49:23 INFO - Running pre-action listener: _resource_record_pre_action 07:49:23 INFO - Running main action method: populate_webroot 07:49:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:49:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:49:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:49:23 INFO - '--tppagecycles', 07:49:23 INFO - '7'], 07:49:23 INFO - 'tests': ['tsvgm']}}, 07:49:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:49:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:49:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:49:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:49:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:49:23 INFO - 'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:49:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:49:23 INFO - 'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['damp', 'tps']}, 07:49:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:49:23 INFO - 'tests': ['damp', 'tps']}, 07:49:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['dromaeo_dom']}, 07:49:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:49:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['basic_compositor_video']}, 07:49:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:49:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['a11yr', 07:49:23 INFO - 'ts_paint', 07:49:23 INFO - 'tpaint', 07:49:23 INFO - 'sessionrestore', 07:49:23 INFO - 'sessionrestore_no_auto_restore', 07:49:23 INFO - 'tabpaint']}, 07:49:23 INFO - 'other-e10s': {'tests': ['a11yr', 07:49:23 INFO - 'ts_paint', 07:49:23 INFO - 'tpaint', 07:49:23 INFO - 'sessionrestore', 07:49:23 INFO - 'sessionrestore_no_auto_restore', 07:49:23 INFO - 'tabpaint']}, 07:49:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:49:23 INFO - 'ts_paint', 07:49:23 INFO - 'tpaint', 07:49:23 INFO - 'sessionrestore', 07:49:23 INFO - 'sessionrestore_no_auto_restore', 07:49:23 INFO - 'tabpaint']}, 07:49:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:49:23 INFO - 'ts_paint', 07:49:23 INFO - 'tpaint', 07:49:23 INFO - 'sessionrestore', 07:49:23 INFO - 'sessionrestore_no_auto_restore', 07:49:23 INFO - 'tabpaint']}, 07:49:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['a11yr', 07:49:23 INFO - 'ts_paint', 07:49:23 INFO - 'tpaint', 07:49:23 INFO - 'sessionrestore', 07:49:23 INFO - 'sessionrestore_no_auto_restore', 07:49:23 INFO - 'tabpaint']}, 07:49:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['a11yr', 07:49:23 INFO - 'ts_paint', 07:49:23 INFO - 'tpaint', 07:49:23 INFO - 'sessionrestore', 07:49:23 INFO - 'sessionrestore_no_auto_restore', 07:49:23 INFO - 'tabpaint']}, 07:49:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['tsvgx', 07:49:23 INFO - 'tsvgr_opacity', 07:49:23 INFO - 'tart', 07:49:23 INFO - 'tscrollx', 07:49:23 INFO - 'cart', 07:49:23 INFO - 'tsvg_static']}, 07:49:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:49:23 INFO - 'tsvgr_opacity', 07:49:23 INFO - 'tart', 07:49:23 INFO - 'tscrollx', 07:49:23 INFO - 'cart', 07:49:23 INFO - 'tsvg_static']}, 07:49:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:49:23 INFO - 'talos_options': ['--disable-e10s'], 07:49:23 INFO - 'tests': ['tp5o']}, 07:49:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:49:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:49:23 INFO - 'talos_options': ['--disable-e10s', 07:49:23 INFO - '--xperf_path', 07:49:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:23 INFO - 'tests': ['tp5n']}, 07:49:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:49:23 INFO - 'talos_options': ['--xperf_path', 07:49:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:23 INFO - 'tests': ['tp5n']}}, 07:49:23 INFO - 'talos.zip': {'path': '', 07:49:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:49:23 INFO - Running post-action listener: _resource_record_post_action 07:49:23 INFO - [mozharness: 2017-05-24 14:49:23.671806Z] Finished populate-webroot step (success) 07:49:23 INFO - [mozharness: 2017-05-24 14:49:23.671943Z] Running create-virtualenv step. 07:49:23 INFO - Running pre-action listener: _resource_record_pre_action 07:49:23 INFO - Running main action method: create_virtualenv 07:49:23 INFO - Creating virtualenv /builds/slave/test/build/venv 07:49:23 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 07:49:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:49:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:49:23 INFO - Using real prefix '/tools/python27' 07:49:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:49:24 INFO - Installing distribute.............................................................................................................................................................................................done. 07:49:27 INFO - Installing pip.................done. 07:49:27 INFO - Return code: 0 07:49:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:49:27 INFO - Reading from file tmpfile_stdout 07:49:27 INFO - Output received: 07:49:27 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) 07:49:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:49: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')]} 07:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:28 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:28 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 07:49:28 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 07:49:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:28 INFO - 'HOME': '/Users/cltbld', 07:49:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:28 INFO - 'LOGNAME': 'cltbld', 07:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:28 INFO - 'MOZ_NO_REMOTE': '1', 07:49:28 INFO - 'NO_EM_RESTART': '1', 07:49:28 INFO - 'PAGER': '/bin/cat', 07:49:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:28 INFO - 'PWD': '/builds/slave/test', 07:49:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:28 INFO - 'SHELL': '/bin/bash', 07:49:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:28 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:28 INFO - 'USER': 'cltbld', 07:49:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:28 INFO - 'XPC_FLAGS': '0x0', 07:49:28 INFO - 'XPC_SERVICE_NAME': '0', 07:49:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:28 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 07:49:28 INFO - Cleaning up... 07:49:28 INFO - Return code: 0 07:49:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:29 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:29 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 07:49:29 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 07:49:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:29 INFO - 'HOME': '/Users/cltbld', 07:49:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:29 INFO - 'LOGNAME': 'cltbld', 07:49:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:29 INFO - 'MOZ_NO_REMOTE': '1', 07:49:29 INFO - 'NO_EM_RESTART': '1', 07:49:29 INFO - 'PAGER': '/bin/cat', 07:49:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:29 INFO - 'PWD': '/builds/slave/test', 07:49:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:29 INFO - 'SHELL': '/bin/bash', 07:49:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:29 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:29 INFO - 'USER': 'cltbld', 07:49:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:29 INFO - 'XPC_FLAGS': '0x0', 07:49:29 INFO - 'XPC_SERVICE_NAME': '0', 07:49:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:29 INFO - Downloading/unpacking psutil>=3.1.1 07:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:49:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:31 INFO - Installing collected packages: psutil 07:49:31 INFO - Running setup.py install for psutil 07:49:31 INFO - building 'psutil._psutil_osx' extension 07:49:31 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 07:49:32 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 07:49:32 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 07:49:32 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 07:49:32 INFO - building 'psutil._psutil_posix' extension 07:49:32 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 07:49:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:49:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:49:32 INFO - ^ 07:49:32 INFO - 1 warning generated. 07:49:32 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 07:49:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:32 INFO - Successfully installed psutil 07:49:32 INFO - Cleaning up... 07:49:32 INFO - Return code: 0 07:49:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:49: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')]} 07:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49: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')]} 07:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:32 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:32 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 07:49:32 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 07:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:32 INFO - 'HOME': '/Users/cltbld', 07:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:32 INFO - 'LOGNAME': 'cltbld', 07:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:32 INFO - 'MOZ_NO_REMOTE': '1', 07:49:32 INFO - 'NO_EM_RESTART': '1', 07:49:32 INFO - 'PAGER': '/bin/cat', 07:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:32 INFO - 'PWD': '/builds/slave/test', 07:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:32 INFO - 'SHELL': '/bin/bash', 07:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:32 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:32 INFO - 'USER': 'cltbld', 07:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:32 INFO - 'XPC_FLAGS': '0x0', 07:49:32 INFO - 'XPC_SERVICE_NAME': '0', 07:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:49:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:49:34 INFO - Installing collected packages: mozsystemmonitor 07:49:34 INFO - Running setup.py install for mozsystemmonitor 07:49:34 INFO - Successfully installed mozsystemmonitor 07:49:34 INFO - Cleaning up... 07:49:34 INFO - Return code: 0 07:49:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:49:34 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')]} 07:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:34 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')]} 07:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:34 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:34 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 07:49:34 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 07:49:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:34 INFO - 'HOME': '/Users/cltbld', 07:49:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:34 INFO - 'LOGNAME': 'cltbld', 07:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:34 INFO - 'MOZ_NO_REMOTE': '1', 07:49:34 INFO - 'NO_EM_RESTART': '1', 07:49:34 INFO - 'PAGER': '/bin/cat', 07:49:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:34 INFO - 'PWD': '/builds/slave/test', 07:49:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:34 INFO - 'SHELL': '/bin/bash', 07:49:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:34 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:34 INFO - 'USER': 'cltbld', 07:49:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:34 INFO - 'XPC_FLAGS': '0x0', 07:49:34 INFO - 'XPC_SERVICE_NAME': '0', 07:49:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:35 INFO - Downloading/unpacking jsonschema==2.5.1 07:49: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 07:49: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 07:49: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 07:49: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 07:49:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:49:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:49:36 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 07:49:36 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 07:49:36 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 07:49:36 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 07:49:36 INFO - Downloading functools32-3.2.3-2.tar.gz 07:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:49:36 INFO - warning: no files found matching '*.txt' 07:49:36 INFO - no previously-included directories found matching 'build' 07:49:36 INFO - no previously-included directories found matching 'dist' 07:49:36 INFO - no previously-included directories found matching '.git*' 07:49:36 INFO - Installing collected packages: jsonschema, functools32 07:49:36 INFO - Running setup.py install for functools32 07:49:36 INFO - warning: no files found matching '*.txt' 07:49:36 INFO - no previously-included directories found matching 'build' 07:49:36 INFO - no previously-included directories found matching 'dist' 07:49:36 INFO - no previously-included directories found matching '.git*' 07:49:36 INFO - Successfully installed jsonschema functools32 07:49:36 INFO - Cleaning up... 07:49:37 INFO - Return code: 0 07:49:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:49: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')]} 07:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49: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')]} 07:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:37 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:37 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 07:49:37 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 07:49:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:37 INFO - 'HOME': '/Users/cltbld', 07:49:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:37 INFO - 'LOGNAME': 'cltbld', 07:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:37 INFO - 'MOZ_NO_REMOTE': '1', 07:49:37 INFO - 'NO_EM_RESTART': '1', 07:49:37 INFO - 'PAGER': '/bin/cat', 07:49:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:37 INFO - 'PWD': '/builds/slave/test', 07:49:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:37 INFO - 'SHELL': '/bin/bash', 07:49:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:37 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:37 INFO - 'USER': 'cltbld', 07:49:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:37 INFO - 'XPC_FLAGS': '0x0', 07:49:37 INFO - 'XPC_SERVICE_NAME': '0', 07:49:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:49:37 INFO - Cleaning up... 07:49:37 INFO - Return code: 0 07:49:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:49: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')]} 07:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49: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')]} 07:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:37 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:37 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 07:49:37 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 07:49:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:37 INFO - 'HOME': '/Users/cltbld', 07:49:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:37 INFO - 'LOGNAME': 'cltbld', 07:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:37 INFO - 'MOZ_NO_REMOTE': '1', 07:49:37 INFO - 'NO_EM_RESTART': '1', 07:49:37 INFO - 'PAGER': '/bin/cat', 07:49:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:37 INFO - 'PWD': '/builds/slave/test', 07:49:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:37 INFO - 'SHELL': '/bin/bash', 07:49:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:37 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:37 INFO - 'USER': 'cltbld', 07:49:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:37 INFO - 'XPC_FLAGS': '0x0', 07:49:37 INFO - 'XPC_SERVICE_NAME': '0', 07:49:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:37 INFO - Downloading/unpacking blobuploader==1.2.4 07:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:38 INFO - Downloading blobuploader-1.2.4.tar.gz 07:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:49:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:49:39 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 07:49:39 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 07:49:39 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 07:49:39 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 07:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:49:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:49:39 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 07:49:39 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 07:49:39 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 07:49:39 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 07:49:39 INFO - Downloading docopt-0.6.1.tar.gz 07:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:49:39 INFO - Installing collected packages: blobuploader, requests, docopt 07:49:39 INFO - Running setup.py install for blobuploader 07:49:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:49:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:49:40 INFO - Running setup.py install for requests 07:49:40 INFO - Running setup.py install for docopt 07:49:40 INFO - Successfully installed blobuploader requests docopt 07:49:40 INFO - Cleaning up... 07:49:40 INFO - Return code: 0 07:49:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:40 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:40 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 07:49:40 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 07:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:40 INFO - 'HOME': '/Users/cltbld', 07:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:40 INFO - 'LOGNAME': 'cltbld', 07:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:40 INFO - 'MOZ_NO_REMOTE': '1', 07:49:40 INFO - 'NO_EM_RESTART': '1', 07:49:40 INFO - 'PAGER': '/bin/cat', 07:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:40 INFO - 'PWD': '/builds/slave/test', 07:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:40 INFO - 'SHELL': '/bin/bash', 07:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:40 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:40 INFO - 'USER': 'cltbld', 07:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:40 INFO - 'XPC_FLAGS': '0x0', 07:49:40 INFO - 'XPC_SERVICE_NAME': '0', 07:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-u2xuUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-FbxnPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-fTQzKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-QHYqlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-6NVnd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-RJe4oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-0SLmR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-kT6clI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:41 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-RgZx6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Y8GDGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-wGJrOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Gd8jHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-RHTJu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-YTLLF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-qRDozN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-nLryT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-1_giWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:49:42 INFO - Running setup.py install for manifestparser 07:49:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:49:42 INFO - Running setup.py install for mozcrash 07:49:43 INFO - Running setup.py install for mozdebug 07:49:43 INFO - Running setup.py install for mozdevice 07:49:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Running setup.py install for mozfile 07:49:43 INFO - Running setup.py install for mozhttpd 07:49:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Running setup.py install for mozinfo 07:49:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Running setup.py install for mozInstall 07:49:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Running setup.py install for mozleak 07:49:44 INFO - Running setup.py install for mozlog 07:49:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for moznetwork 07:49:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for mozprocess 07:49:44 INFO - Running setup.py install for mozprofile 07:49:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for mozrunner 07:49:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for mozscreenshot 07:49:45 INFO - Running setup.py install for moztest 07:49:45 INFO - Running setup.py install for mozversion 07:49:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:49:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:49:45 INFO - Cleaning up... 07:49:45 INFO - Return code: 0 07:49:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:45 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')]} 07:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:45 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')]} 07:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:45 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:45 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 07:49:45 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 07:49:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:45 INFO - 'HOME': '/Users/cltbld', 07:49:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:45 INFO - 'LOGNAME': 'cltbld', 07:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:45 INFO - 'MOZ_NO_REMOTE': '1', 07:49:45 INFO - 'NO_EM_RESTART': '1', 07:49:45 INFO - 'PAGER': '/bin/cat', 07:49:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:45 INFO - 'PWD': '/builds/slave/test', 07:49:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:45 INFO - 'SHELL': '/bin/bash', 07:49:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:45 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:45 INFO - 'USER': 'cltbld', 07:49:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:45 INFO - 'XPC_FLAGS': '0x0', 07:49:45 INFO - 'XPC_SERVICE_NAME': '0', 07:49:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-zZ1Sak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-xMeN3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-8vE9EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-tROIBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-JVDWnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-DT5Yf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Zsz2rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-mxAmAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-tOE__A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ifSWV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Z3TCIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-N9Rg1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:46 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-lM_2Wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:47 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)) 07:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-1ygTti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:47 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)) 07:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-D07U0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:47 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)) 07:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hi09aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:47 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)) 07:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-8fudAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:47 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)) 07:49:47 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)) 07:49:47 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)) 07:49:47 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)) 07:49:47 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)) 07:49:47 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)) 07:49:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:49:47 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 07:49:47 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 07:49:47 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 07:49:47 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 07:49:48 INFO - Downloading blessings-1.6.tar.gz 07:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:49:49 INFO - Installing collected packages: blessings 07:49:49 INFO - Running setup.py install for blessings 07:49:49 INFO - Successfully installed blessings 07:49:49 INFO - Cleaning up... 07:49:49 INFO - Return code: 0 07:49:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:49:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:49 INFO - Reading from file tmpfile_stdout 07:49:49 INFO - Current package versions: 07:49:49 INFO - blessings == 1.6 07:49:49 INFO - blobuploader == 1.2.4 07:49:49 INFO - docopt == 0.6.1 07:49:49 INFO - functools32 == 3.2.3-2 07:49:49 INFO - jsonschema == 2.5.1 07:49:49 INFO - manifestparser == 1.1 07:49:49 INFO - mozInstall == 1.12 07:49:49 INFO - mozcrash == 1.0 07:49:49 INFO - mozdebug == 0.1 07:49:49 INFO - mozdevice == 0.49 07:49:49 INFO - mozfile == 1.2 07:49:49 INFO - mozhttpd == 0.7 07:49:49 INFO - mozinfo == 0.9 07:49:49 INFO - mozleak == 0.1 07:49:49 INFO - mozlog == 3.4 07:49:49 INFO - moznetwork == 0.27 07:49:49 INFO - mozprocess == 0.25 07:49:49 INFO - mozprofile == 0.28 07:49:49 INFO - mozrunner == 6.13 07:49:49 INFO - mozscreenshot == 0.1 07:49:49 INFO - mozsystemmonitor == 0.3 07:49:49 INFO - moztest == 0.8 07:49:49 INFO - mozversion == 1.4 07:49:49 INFO - psutil == 3.1.1 07:49:49 INFO - requests == 1.2.3 07:49:49 INFO - wsgiref == 0.1.2 07:49:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:49 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')]} 07:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:49 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')]} 07:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:49 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:49 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 07:49:49 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 07:49:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:49 INFO - 'HOME': '/Users/cltbld', 07:49:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:49 INFO - 'LOGNAME': 'cltbld', 07:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:49 INFO - 'MOZ_NO_REMOTE': '1', 07:49:49 INFO - 'NO_EM_RESTART': '1', 07:49:49 INFO - 'PAGER': '/bin/cat', 07:49:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:49 INFO - 'PWD': '/builds/slave/test', 07:49:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:49 INFO - 'SHELL': '/bin/bash', 07:49:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:49 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:49 INFO - 'USER': 'cltbld', 07:49:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:49 INFO - 'XPC_FLAGS': '0x0', 07:49:49 INFO - 'XPC_SERVICE_NAME': '0', 07:49:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 INFO - Cleaning up... 07:49:49 INFO - Return code: 0 07:49:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:49:49 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')]} 07:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:49 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')]} 07:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:49 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 0x109482b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109485768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc86b4e5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, '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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 07:49:49 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 07:49:49 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 07:49:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:49:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:49 INFO - 'HOME': '/Users/cltbld', 07:49:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:49 INFO - 'LOGNAME': 'cltbld', 07:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:49 INFO - 'MOZ_NO_REMOTE': '1', 07:49:49 INFO - 'NO_EM_RESTART': '1', 07:49:49 INFO - 'PAGER': '/bin/cat', 07:49:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:49 INFO - 'PWD': '/builds/slave/test', 07:49:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:49 INFO - 'SHELL': '/bin/bash', 07:49:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:49:49 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:49:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:49 INFO - 'USER': 'cltbld', 07:49:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:49 INFO - 'XPC_FLAGS': '0x0', 07:49:49 INFO - 'XPC_SERVICE_NAME': '0', 07:49:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:49:50 INFO - Cleaning up... 07:49:50 INFO - Return code: 0 07:49:50 INFO - Running post-action listener: _resource_record_post_action 07:49:50 INFO - Running post-action listener: _start_resource_monitoring 07:49:50 INFO - Starting resource monitoring. 07:49:50 INFO - [mozharness: 2017-05-24 14:49:50.059497Z] Finished create-virtualenv step (success) 07:49:50 INFO - [mozharness: 2017-05-24 14:49:50.060440Z] Running install step. 07:49:50 INFO - Running pre-action listener: _resource_record_pre_action 07:49:50 INFO - Running main action method: install 07:49:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:50 INFO - Reading from file tmpfile_stdout 07:49:50 INFO - Detecting whether we're running mozinstall >=1.0... 07:49:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:49:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:49:50 INFO - Reading from file tmpfile_stdout 07:49:50 INFO - Output received: 07:49:50 INFO - Usage: mozinstall [options] installer 07:49:50 INFO - Options: 07:49:50 INFO - -h, --help show this help message and exit 07:49:50 INFO - -d DEST, --destination=DEST 07:49:50 INFO - Directory to install application into. [default: 07:49:50 INFO - "/builds/slave/test"] 07:49:50 INFO - --app=APP Application being installed. [default: firefox] 07:49:50 INFO - mkdir: /builds/slave/test/build/application 07:49:50 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'] 07:49:50 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 07:50:10 INFO - Reading from file tmpfile_stdout 07:50:10 INFO - Output received: 07:50:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:50:10 INFO - Running post-action listener: _resource_record_post_action 07:50:10 INFO - [mozharness: 2017-05-24 14:50:10.545169Z] Finished install step (success) 07:50:10 INFO - [mozharness: 2017-05-24 14:50:10.545306Z] Running run-tests step. 07:50:10 INFO - Running pre-action listener: _resource_record_pre_action 07:50:10 INFO - Running main action method: run_tests 07:50:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:50:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:50:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:50:10 INFO - 2017-05-24 07:50:10.559 screenresolution[1622:7395] starting screenresolution argv=screenresolution get 07:50:10 INFO - 2017-05-24 07:50:10.562 screenresolution[1622:7395] Display 0: 1600x1200x32@60 07:50:10 INFO - 2017-05-24 07:50:10.567 screenresolution[1623:7398] starting screenresolution argv=screenresolution list 07:50:10 INFO - Available Modes on Display 0 07:50:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:50:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:50:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:50:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:50:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:50:10 INFO - Intel Iris: 07:50:10 INFO - Chipset Model: Intel Iris 07:50:10 INFO - Type: GPU 07:50:10 INFO - Bus: Built-In 07:50:10 INFO - VRAM (Dynamic, Max): 1536 MB 07:50:10 INFO - Vendor: Intel (0x8086) 07:50:10 INFO - Device ID: 0x0a2e 07:50:10 INFO - Revision ID: 0x0009 07:50:10 INFO - Displays: 07:50:10 INFO - Display: 07:50:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:50:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:50:10 INFO - Display Serial Number: 1600x1200 60 07:50:10 INFO - Main Display: Yes 07:50:10 INFO - Mirror: Off 07:50:10 INFO - Online: Yes 07:50:10 INFO - Rotation: Supported 07:50:10 INFO - Return code: 0 07:50:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:50:10 INFO - Python 2.7.3 07:50:10 INFO - Return code: 0 07:50:10 INFO - grabbing minidump binary from tooltool 07:50:10 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')]} 07:50:10 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 0x109260d30>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109255be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:50:10 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 07:50:10 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 07:50:10 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 07:50:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:50:10 INFO - Return code: 0 07:50:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:50:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:50:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:50:10 INFO - ENV: RUST_BACKTRACE is now 1 07:50:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:50:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:50:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 07:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --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 07:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LhuLIRS8x9/Render', 07:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:10 INFO - 'HOME': '/Users/cltbld', 07:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:10 INFO - 'LOGNAME': 'cltbld', 07:50:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:50:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:10 INFO - 'MOZ_NO_REMOTE': '1', 07:50:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:50:10 INFO - 'NO_EM_RESTART': '1', 07:50:10 INFO - 'PAGER': '/bin/cat', 07:50:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:10 INFO - 'PWD': '/builds/slave/test', 07:50:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:10 INFO - 'RUST_BACKTRACE': '1', 07:50:10 INFO - 'SHELL': '/bin/bash', 07:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWnrO8YoEb/Listeners', 07:50:10 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 07:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:10 INFO - 'USER': 'cltbld', 07:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:10 INFO - 'XPC_FLAGS': '0x0', 07:50:10 INFO - 'XPC_SERVICE_NAME': '0', 07:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 07:50:11 INFO - mozversion application_buildid: 20170524055525 07:50:11 INFO - mozversion application_changeset: 7166b44658df0723b16d43b7f884deb73c99f0ba 07:50:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:50:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:50:11 INFO - mozversion application_name: Firefox 07:50:11 INFO - mozversion application_remotingname: firefox-dev 07:50:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:50:11 INFO - mozversion application_vendor: Mozilla 07:50:11 INFO - mozversion application_version: 54.0 07:50:11 INFO - mozversion platform_buildid: 20170524055525 07:50:11 INFO - mozversion platform_changeset: 7166b44658df0723b16d43b7f884deb73c99f0ba 07:50:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:50:11 INFO - mozversion platform_version: 54.0 07:50:11 INFO - using testdate: 1495637411 07:50:11 INFO - actual date: 1495637411 07:50:11 INFO - starting webserver on 'localhost:49217' 07:50:11 INFO - SUITE-START | Running 2 tests 07:50:11 INFO - TEST-START | dromaeo_css 07:50:11 INFO - operating with platform_type : mac_ 07:50:11 INFO - Initialising browser for dromaeo_css test... 07:50:11 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpslWfFs/profile http://localhost:49217/getInfo.html) 07:50:13 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 07:50:13 INFO - PROCESS | 1633 | colorDepth:24 07:50:13 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 07:50:13 INFO - PROCESS | 1633 | __metrics 07:50:13 INFO - PROCESS | 1633 | 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" 07:50:13 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:14 INFO - TEST-INFO | 1633: exit 0 07:50:14 INFO - Browser initialized. 07:50:14 INFO - Running cycle 1/1 for dromaeo_css test... 07:50:14 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpslWfFs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:26 INFO - PROCESS | 1637 | RSS: Main: 197443584 07:50:26 INFO - PROCESS | 1637 | 07:53:30 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49217/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49217/tests/dromaeo/cssquery-ext.html) 07:53:32 INFO - PROCESS | 1637 | RSS: Main: 313192448 07:53:32 INFO - PROCESS | 1637 | 07:56:35 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49217/tests/dromaeo/cssquery-ext.html (next: http://localhost:49217/tests/dromaeo/cssquery-jquery.html) 07:56:35 INFO - PROCESS | 1637 | RSS: Main: 332283904 07:56:35 INFO - PROCESS | 1637 | 07:59:38 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49217/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49217/tests/dromaeo/cssquery-mootools.html) 07:59:38 INFO - PROCESS | 1637 | RSS: Main: 308342784 07:59:38 INFO - PROCESS | 1637 | 08:02:41 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49217/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49217/tests/dromaeo/cssquery-prototype.html) 08:02:42 INFO - PROCESS | 1637 | RSS: Main: 316551168 08:02:42 INFO - PROCESS | 1637 | 08:05:45 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49217/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49217/tests/dromaeo/cssquery-yui.html) 08:05:46 INFO - PROCESS | 1637 | RSS: Main: 296210432 08:05:46 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49217/tests/dromaeo/cssquery-yui.html (next: http://localhost:49217/tests/dromaeo/cssquery-dojo.html) 08:08:49 INFO - PROCESS | 1637 | RSS: Main: 346939392 08:08:49 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | __start_tp_report 08:08:49 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 08:08:49 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 08:08:49 INFO - PROCESS | 1637 | |i|pagename|runs| 08:08:49 INFO - PROCESS | 1637 | |0;dojo.html;3330.6003279606443;3337.9151775672904;3338.949915751264;3366;3383.390989621869;5576.414476479972;5694.857628559288;5698.943010569893;5712.914306285409;5741.224934633823;5629.268195134637;5735.139729040646;5745.109508026263;5759.366469688342;5777.4511421415;5163.044836705203;5211.45279745627;5228.686278823264;5242.397124330693;5276.234945932837;5136.922946155811;5168.715720635363;5216.26972223889;5229.686218826863;5243.9213411798855;5301.655392399501;5303.814366497173;5305.363356397238;5315.548178404821;5347.0909945309195;5313.6014798889855;5322.92015619766;5327.786888524473;5334.759935802898;5381.5425688816495;4458.799354029076;4969.378827646544;5054.393472783253;5180.663256888291;5286.3127793387;5603.187537807036;5621.690807005613;5625.71871406428;5676.489115979541;5680.6591604503865;6268.279147898004;6287.622742635411;6301.59039662425;6319.431251187415;6339.619622822599;5207.297014902978;6344.889644312273;6345.8413539661415;6350.015747559136;6353.396427339396;5609.214709940612;5651.378348381675;5713.685747283939;5797.797077102281;5821.767129314865;6309.968450157811;6318.241810982619;6321.873562528724;6333.556651034383;6339.619622822645;6059.091136329585;6118.265808103056;6119.694015299307;6130.141780150782;6134.754609815557;6221.96889015552;6300.905486417708;6314.17915670969;6315.210598675166;6317.3366796486625;6008.549358798107;6249.312575616678;6254.21822272216;6258.530610204253;6258.593191442542;6147.877042459126;6152.692365381802;6162.014077747539;6194;6209.285932117774;4775.450823155382;4803.615710743132;4804.8798780030775;4806.44725856524;4835.468098509161;3556.8043757593064;4273.93151712072;4289.378040184219;4297.914041719148;4330.35044743291;5369.382521010056;5400.783968641211;5479.808206712746;5572;5588.748506317144;6202.596831205957;6212.844678883064;6223.1287619733275;6237.03325984464;6242.687865606701;5602.243697100839;5632.464915832989;5634.464725851038;5644.294463192101;5660.490555849993;5379.058664733734;5417.106177480671;5420.891582168413;5428.4028756836715;5445;6391.392817682313;6399.488040956805;6402.10370548133;6411.551191416649;6433.067205255305;6111.07520520229;6145.723442445012;6291.307956124731;6341.682915854281;6388.776392826228;6383.96808015957;6439.581427207404;6440.871182576228;6463.353664633499;6467;6280.811575652738;6283.96858015707;6300.621962682254;6304.810855674337;6321.8735625288155;6122.428649698624;6149.200603921461;6216.937830621636;6264.091706702594;6333.20834895638;4833.540813622701;6138.508919286535;6190;6192.721327540181;6193.566450348432;6197.349278325892;6209.410106039919;6211.751529938932;6227.159333489921;6239.0953311770445;5802.390748971466;5824.475797178275;6003.189569408074;6004.39956004396;6048.969755151372;6132.998044146438;6137.8979746733885;6165.691715414122;6183.196184495987;6196.969015154896;5996.938917489954;6011.819645410645;6020.488258498077;6060.939390606038;6086.878262434638 08:08:49 INFO - PROCESS | 1637 | |1;ext.html;783;795.4908858330667;816.6039470856635;882.7131182365732;895.225629830197;28887.42225155498;40588;41268.793656031754;41340.79329603355;41421.96445088871;42322.57677423225;42499.57500424995;42512.57487425125;42553.148937021244;42565.787171064105;7478.663460144279;9407.965123836373;9552.474613896233;9569.712908612753;9583.760405989837;7570.12943511495;11322.830157547643;11566.942165289203;11676.241044332133;11700.00549953255;11524.481398337095;11545.01867341277;11564.653060408202;11574.016208622277;11601.767964640701;11475.368854712988;11481.483333250024;11494.36780977046;11507.194496385257;11510.021648159875;11227.045701115416;11266.380349080797;11280.2667826591;11286.097112231084;11288.94355528217;38939.61060389388;39094.80452597747;39120.80439597784;39143.82568522949;39193.80403097995;3809.276497143522;3853.8651147209994;3894.084890050836;3896.766194028339;3953.4069889516513;27680.584791228146;27791.16626501208;29169.41661166786;29938;30032.699673003208;2972.4946759051013;3143.465610846162;3237.223066464055;3253.6746325367276;3258.413485572596;330823;333185;333429;334166;334234;53254.46745532573;53527.73236133794;53559.732201338746;53625.731871340395;53625.92748145093;55642.44357556454;55758;55816.441835581936;55823.44176558264;55953.72023139858;14493.565193044014;14574.781378279335;14602.85397146015;14633.341499632543;14634.634134146732;10799.298045627009;11008.449577521253;12638.684032899251;12656.620301390973;12659.556915507914;11082.667519974413;12472;12667.809982850265;12791.744165116646;12905.806412903814;4582.289745089516;4672.345871577982;4921.212605983025;5584.329880414376;5648.774049038075;1075.5052675769234;1119.4962266979728;1150.9942450287863;1171.1333613126226;1219.737756382382;27626.44747105087;27631;28088.8595557025;28099.57850632242;28267.57598636022;834.9527108628356;865.4253595553179;1157.062779148892;1195.647284051207;1307.6207899709168;12386.132970692146;13144.802827957588;13221.471141154247;13231.2722800245;13294.335283235992;269573;270238;296216.51891740836;296278;296722;35490.645093549254;43067.138657227304;43456.565434345255;43529.34705979413;52623;1465.2014652014677;1468.3539242733164;1477.3795006097464;1486.1603194195245;1487.315834716021;27650.308742281602;28990.27524311909;29127.12618621445;29675.961341352948;29724.959626412972;43909.78045109754;44049;44060.559394405645;44067.55932440634;44177.67466975996;252553;255591.7220413886;297221;297359;297768.5111574515;28969.7103028967;32285.838570806995;33143.67425302961;33149.337013259115;33596.32807343888;41478.170436590495;41664.79167604142;41808.1638367237;42336.78831605822;42444.5755542453;28131;29051.983180588577;29359.972400965864;30001.849990749906;30057;28270.71729282681;28790.568141478314;29003.56494652624;29914.102576922727;30111.54832677468;33549.993500195036;34671.95984120481;34686.47970280498;34688.65311346854;34761.304773904885;28401.857990709916;29299.41401171922;29317.85341073281;29337;29997.400051999273;22201.44496387603;22861;23172.420689482897;23581.88209058944;23686.934087966427 08:08:49 INFO - PROCESS | 1637 | |2;jquery.html;425.330105084492;433.8763452416061;435.6188335206694;438.3490516582874;444.8265176581134;19340.90329548354;20619;21666.133354665795;21893.781062189413;21979.890100549495;19480.902595487038;19657.80342196581;21222.62052966556;22318.54929429586;23151.536969260604;6303.905441418371;6990.091288132536;7004.229534751174;7015.473839462035;7075.363217310441;6688.665566721645;6781.627010514419;6859.519833611649;6917.342852429011;6978;6584.341565843425;6870.553414028072;6987.965060174692;7004.614746188958;7005.649717514129;6851.246362900077;6939.514234003622;6942.791716248521;6957;6992.440604751608;6435.163428754278;6764;6831.419329357011;6837.213720422165;6838.00848876915;24949.00203991838;26022.219333420027;26872.328191795164;26883.193504194904;27744.722552774416;2883.2215301868587;2921.4449254641763;2957.5859379686863;2991.1026691992315;2993.925151871199;15534.456294029767;15665.13841738704;15686.52940411778;16108.436204732896;16457.83542164575;1994.3518356534184;2039.8980050997538;2042.234162189179;2061.4331058958755;2105.5788842231614;158359.2082039594;161554.99999999884;161622;162450;163429.3657063426;14983.775243371358;19207.751496152985;19862.5309305408;20059.799402005792;20096.49758756043;32195;32709.509357359657;32743.836280818443;32760;32789.52447139884;9442.811143777086;9647.807043859084;9833.360831545926;10009.249306302056;10028.348157369748;7283.781486555412;7342.559446433231;8096.595170241464;8104.149064348277;8237.79405514867;7751.844963100706;8983.101689831095;9279.164875161268;9351.392159509745;9352.579133938989;2911.505044142501;3447.7586568940437;3600.6039335673054;3652.19651676636;3759.7180211484247;952.9618815247312;1051.3218973762034;1070.817960946641;1078.412265315405;1092.9508172132273;21631.134754609953;22086.337409877728;22109.778902211092;22121.77878221197;22200.666989995163;1015.5683834370363;1091.956321747137;1096.5010920031366;1118.105515587543;1122.421952694363;9101;10840.891591084146;11429.942850285861;11507.367094809699;11527.193096483166;409193.90806092153;767502;783786;784470;792532;147142;158293;159023;161684.57473137914;163929.18035409748;1221.3465795799204;1230.2249582762802;1276.9465191217282;1281.705207802192;1287.2469605280828;15693.293801778718;20004.89997550003;20156;20716.482087947923;20746.688799668318;47883.127012379366;53675.38973830792;57192.42807571871;58246.4175358241;60785.696071519356;82350.94122646742;82449.58775206086;86942.56528717316;88089;91646;10671.35971841692;11208.831867522156;11395.943020284845;11522.481488332878;11925.701857453632;12555.686107847378;12614.747705046031;12677.477226758123;12859.678508037374;12881.35593220354;10830.566777328819;10973.451327433437;11154.721131971766;11176.27354221973;11305.15211359135;10214.744631384276;10256.794864102527;10295.279330447096;10410.531526081497;10611.31026483276;9548.331616786758;11153.776924461303;11217.326960382403;11371.658850234506;11532.59635912739;11285.322880627189;11400;11401.20191586581;11749.530018799494;11840.70398240051;10979.39613321275;10985.560577576807;10992.230543861853;10995.945020274847;11259.831102533304 08:08:49 INFO - PROCESS | 1637 | |3;mootools.html;1359.3814814259513;1593.1874743880624;1619.829917858625;1623.0667366264393;1631.6165701060252;9399.67101151459;9816.901830981687;11075.16936229782;11391.48738306776;11510.654680359603;11235.943820280887;11282.379469129195;11322.15083868711;11333.603323883666;11334.716632084203;2021.8079282468143;2376.714794224691;2421.7457166997474;2423.587990041697;2429.4898071404937;2007.8694884832512;2094.748630164375;2155.267209148889;2373.738888722239;2461.027893981876;2395.9760402396014;2428.86641234732;2431.0397393029743;2432;2434.0385547708643;2395.4490467192554;2424.733279339271;2430.6597076409316;2430.902763889442;2435.2937648082034;2350.4476448034698;2357.976420235793;2369;2373.41851246444;2381.9995601847268;11319.20765546412;11354.091672666167;11370.829437558443;11372.03337716295;11433.656990290221;5824.126381042835;6029.6382217066675;6038.939610603882;6045.818625441244;6049.879002419927;3191.1862475068942;3404.676555727202;3482.66914643111;3487.0584942065752;3488.3023395320783;1077.2728408324429;1114.186643750059;1144.193343692703;1153.0314535789976;1164.673891310426;15060.32228549718;15506.22468876563;15565.37738490459;15745.68508629821;15810.92094539543;4555.362249285103;4562.703424277478;4671.392718946515;4674.252119660838;4744.169770290186;11888.524459021715;11937.28376297425;11946.10404219687;11966.282023078644;11966.940165299118;5853;5853.590248682636;5873.324567674772;5874.206982057453;5908.615939963975;4434.711743736647;4708.882277943078;5097.337346145052;5216.37403511581;5374.596905232123;3248.480243161083;3344.7156991655984;3791.9431208531896;3836.328642487609;3882.2041481496226;991.8264303746815;1003.8845532763826;1021.9897801022043;1022.6522982186092;1027.173125633872;737.8155461134717;836.9748907532784;848.6138806842872;856.4433118473042;859.5229647545672;6365.936340636627;6503.674816259168;6714.026466162379;6817.761378351734;6904.102466679299;1053.2311412668794;1068.407034096084;1070.5985255529176;1097.4128841070149;1145.8395824584566;3710.4805327254207;4178.456800615901;4314.935275970863;4360.236958532244;4477.261251893467;13205;13980.860191397956;13989.86010139906;14005.929970350084;14017.369218384993;13721.931390342985;13856.861431385558;13878.097923634932;13889.16665000083;13907.79138312926;9952.402855828674;10385.584576617002;10497.212709047004;10498.527566259536;10512.26414151002;6945.482446962232;6963.094797676472;6984.40632546219;7017.193022802239;7178.210396856446;12086.879131208576;12320.199187052813;12772.808407873703;12801.93599031999;12841.422136003715;9758.46328451942;11999.580014699444;12068.094892882893;12296.569620063256;12623.93688031554;7688.384929205538;9330.393524420891;9362.016988216139;9479.336446448682;9576.138147566753;8962.775930601787;9227.677031303872;9340.439573625605;10202.846957295795;10297.485125743533;8582.356323275904;8680.305575554066;8722.912770872465;9160.954195228982;9197.448153110834;8568.186022327869;8634.026402805002;8750.956245218733;8852.02204054016;8872.201501864864;8058.556779377191;8061.355091592776;8168;8169.714060007871;8178.918210817816;5293.311869456946;5940.1683764272175;5993.460588547052;6171.444569988723;6231.781887633911;5966.134910438049;5966.910496342645;6168.475679567287;6171.876562468815;6230.7819226326865 08:08:49 INFO - PROCESS | 1637 | |4;prototype.html;303.79190254675547;368.2193749251587;380.9980950095249;383.99999999999983;390.3792969179005;3804.2771873344045;3831;4831.444383895853;4871.295046733641;4968.751562421878;3904.910872683115;4182.602652747984;4263.467066616673;4409.889752756183;4540.84208526826;3600.8132802469872;3612.4647025014397;3850.191459793447;3957.4261732048835;4090.713650044498;3474.496198051281;3853.287141878747;4012.8996775080564;4110.458578033237;4122.443470131524;3553.5608078728155;3733.383991641376;3918.1380096378894;4090.9590904091024;4096.836126554934;3751.212245428463;3800.239952009609;3966.7619942803235;3988.282109220339;4016;3418.6239513653477;3702.685271751904;3705.184859330943;3743.288775132716;3759.4172903200047;3781.220802710006;4320.503142138631;4677.836275730333;4699.718016918996;4745.311929770199;3944.3653706894283;4275.8075886585175;4517.226415000248;4600.9769951150365;4638.6342965087715;4541.772911354419;4904.131083316264;5063.493650634907;5101.51535604117;5118.385793704742;3500.3524347995703;3561.626029266916;3578.8926332210076;4036.979815100906;4164.250434921712;4389.890252743707;4593.885152871205;5005.6996580205305;5410.213739923943;5486.231927530109;3641.7450778445254;4047.170330082326;4200.613797446836;4201.907504048908;4220.388043733641;3815.6947444204397;4005.959940400617;4246.171996460661;4280.4863416390235;4297.763623000703;3797.930128080203;3877.9335682686924;4192.937105943413;4644.164050470948;4666.176962937442;4209.800206941007;4839.612830973514;5090.720010399464;5109.186238885142;5142.228665700175;1342.489853855548;1400.859914008591;1447.8117844680335;1456.5798346611969;1480.7112613040354;954.9092836180552;1002.3711468863097;1103.2828661370013;1114.7324642085841;1121.506537123663;4113.362428823537;4196.3705444183615;4580.335851301609;4591.517890621503;4646.442426908792;4427.40230068943;4678.929816052762;5209.296744939385;5295.655782374133;5301.708406037628;3859.459675645412;3951.6245956634075;4265.808038638207;4511.030128522383;4593.47175074864;2348.7651234876716;2671.1719366996294;2733.412316351994;2744.6157537944705;2761.337279053013;4767.856964291077;4865.221564549726;4900.99529596439;5179.870503237449;5512.531434828004;3942.0289855072465;4670.252759558454;5294.258803767476;5389.568834493231;5440.591955603346;4594.632429405639;4797.160496913096;5377.354717433933;5410.458954104637;5482.616216864861;4710.340552322746;4734.621230301636;4900.828471003498;4998.900021999612;5255.132903071103;4309.5259521453245;4427.446569178853;5193.870153246048;5208.739563021758;5241.082810508222;5016.448190699093;5130.333056702605;5232.895342093213;5248.845254044104;5499.432661691559;3783.7486695416183;3918.3142949983067;4038.9596104038583;4114.9630932688515;4195.8741237762915;4786.808527658994;5086.3896332440345;5173.81891633791;5277.1292736698015;5308.946910530845;4714;5143.71709555978;5167.018266529348;5176.922346164735;5193.974030129825;4441.555844415532;4619.5611416915335;4884.48712885142;5103.923441148311;5137.794488220578;4127.023476040533;4719.882002949954;4989.700617962933;5027.59779217669;5028.34631497903;4395.1429471252795;4601.363329920954;4905.975470122769;5077.339945807022;5078.289039534542;4446.8665940020555;4753.407608451208;4757.191277482905;4832.661713680009;5002.599792016557 08:08:49 INFO - PROCESS | 1637 | |5;yui.html;719.8236432074143;766.4979438467802;774.6010804435716;845.1970627903493;855.5716009574492;15231.31459084341;15928.203589820521;16022.519324420271;16055.75916361254;16082.115483648395;14442.638934026654;14825.851373968851;14900.7019859603;14960.251987400614;15253.31360088796;956.1346980982222;992.9056739609725;995.4276291132592;997.9201663866874;998.3211416236956;990.1187942730975;994.5723338964242;994.955227014786;999.075854834279;999.14073896449;897.7082448204327;1013.9188864890791;1051.6950084475493;1052.0636633396284;1052.6578861869884;1036.331566139843;1036.4299635200648;1036.486938965217;1036.8703912011;1038.2628333882876;764.3006648916235;768.4428789127893;768.6925229908026;770.0874463760406;770.1912991359106;15823.841761582353;16210.108444035573;16261.83738162615;16765.74851377218;16933.407330743365;14346.497872574515;14404.207768572724;14424.783628245585;14434.711305773826;14471.855281447157;11127.610533631285;11155.52180342937;11215.66353009411;11283.717907052307;11369.147313951404;3670.944935825965;3682.355587772156;3709.1098136447326;3801.467794508771;3920.4315374270855;49730.751346243036;84500;96820.51589742077;111784.4410777941;112144.99999999919;10535.683929482127;10878.564857405616;10894.36952040338;11240.831387529193;11426.714332141762;21657.67513487299;22003.78979156162;22030.88984555067;22488.21291254798;22518.099276029105;7957.920420795834;8015;8023.398245131521;8051.282562532968;8059.032916050111;4190.958090419118;4243.130158317537;4271.23117838792;4302.849400271038;4410.184115938561;3239.319742854027;3352.6479719629087;3405.404054290489;3442.087846720621;3514.61339252682;571.5456212311223;572.0561074227558;573.1231216239161;577.9827503593706;585.8857522783017;614.4163045107166;707.989380159298;718;725.836686745478;742.4023660952985;10270.178385729123;10420.583176673;10468.633597824191;10532.631357902437;10533.736656583493;680.4825239715364;682.2699711308951;714.1358955663601;726.564061563058;743.9739609113655;4661.743604101739;4845.685030473079;5000;5083.517065878736;5116.181410974301;80253.59873200596;105340.47329763303;106554.9344506545;107051;108509;64390.678046609464;67802.9829552557;79366.80949785758;90049.54975225082;91027.54486227658;75407.62296188484;76425.35746425313;76979.61510192414;78726.99999999885;79008.60495697599;9079.954600226956;9453.480058596842;10815.297005694605;11169.329840209288;11208.495617697386;44379.11241775211;46090;49030;50773.23840142327;51342.74328628482;57989.710051449474;63086;64113;64980;65946.01080983694;12876.613701588967;12924.030697697523;13126.803097953347;13209.405576749261;13275.070745047755;14711.3379897907;14815.736927803395;14875.776863346842;15062.698746024798;15199.620009499851;13651.590452286448;13893.305334733424;14026.438942442595;14290.214038227581;14531.854681453473;11173.7795012968;13521.323933803074;13937.93031034879;14134.929325353307;14405.351759170653;12787.61637150887;12853.742925141632;13658.658533536343;13673.521426750016;13860.653483662989;8003.559804210824;9971.152452041657;10245;10326.793464130526;10373.481325933522;10847.67456976292;11143.609973650884;11325.773484530428;11577.768444631229;11601.303921764384 08:08:49 INFO - PROCESS | 1637 | __end_tp_report 08:08:49 INFO - PROCESS | 1637 | __start_cc_report 08:08:49 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1375 08:08:49 INFO - PROCESS | 1637 | __end_cc_report 08:08:49 INFO - PROCESS | 1637 | __startTimestamp1495638529564__endTimestamp 08:08:49 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | ------- Summary: start ------- 08:08:49 INFO - PROCESS | 1637 | Number of tests: 6 08:08:49 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5736.88 Median:6008.55 stddev:691.79 (11.5%) stddev-sans-first:667.66 08:08:49 INFO - PROCESS | 1637 | Values: 3330.6 3337.9 3338.9 3366.0 3383.4 5576.4 5694.9 5698.9 5712.9 5741.2 5629.3 5735.1 5745.1 5759.4 5777.5 5163.0 5211.5 5228.7 5242.4 5276.2 5136.9 5168.7 5216.3 5229.7 5243.9 5301.7 5303.8 5305.4 5315.5 5347.1 5313.6 5322.9 5327.8 5334.8 5381.5 4458.8 4969.4 5054.4 5180.7 5286.3 5603.2 5621.7 5625.7 5676.5 5680.7 6268.3 6287.6 6301.6 6319.4 6339.6 5207.3 6344.9 6345.8 6350.0 6353.4 5609.2 5651.4 5713.7 5797.8 5821.8 6310.0 6318.2 6321.9 6333.6 6339.6 6059.1 6118.3 6119.7 6130.1 6134.8 6222.0 6300.9 6314.2 6315.2 6317.3 6008.5 6249.3 6254.2 6258.5 6258.6 6147.9 6152.7 6162.0 6194.0 6209.3 4775.5 4803.6 4804.9 4806.4 4835.5 3556.8 4273.9 4289.4 4297.9 4330.4 5369.4 5400.8 5479.8 5572.0 5588.7 6202.6 6212.8 6223.1 6237.0 6242.7 5602.2 5632.5 5634.5 5644.3 5660.5 5379.1 5417.1 5420.9 5428.4 5445.0 6391.4 6399.5 6402.1 6411.6 6433.1 6111.1 6145.7 6291.3 6341.7 6388.8 6384.0 6439.6 6440.9 6463.4 6467.0 6280.8 6284.0 6300.6 6304.8 6321.9 6122.4 6149.2 6216.9 6264.1 6333.2 4833.5 6138.5 6190.0 6192.7 6193.6 6197.3 6209.4 6211.8 6227.2 6239.1 5802.4 5824.5 6003.2 6004.4 6049.0 6133.0 6137.9 6165.7 6183.2 6197.0 5996.9 6011.8 6020.5 6060.9 6086.9 08:08:49 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:45685.56 Median:163541.98 stddev:78786.62 (48.2%) stddev-sans-first:78935.50 08:08:49 INFO - PROCESS | 1637 | Values: 783.0 795.5 816.6 882.7 895.2 28887.4 40588.0 41268.8 41340.8 41422.0 42322.6 42499.6 42512.6 42553.1 42565.8 7478.7 9408.0 9552.5 9569.7 9583.8 7570.1 11322.8 11566.9 11676.2 11700.0 11524.5 11545.0 11564.7 11574.0 11601.8 11475.4 11481.5 11494.4 11507.2 11510.0 11227.0 11266.4 11280.3 11286.1 11288.9 38939.6 39094.8 39120.8 39143.8 39193.8 3809.3 3853.9 3894.1 3896.8 3953.4 27680.6 27791.2 29169.4 29938.0 30032.7 2972.5 3143.5 3237.2 3253.7 3258.4 330823.0 333185.0 333429.0 334166.0 334234.0 53254.5 53527.7 53559.7 53625.7 53625.9 55642.4 55758.0 55816.4 55823.4 55953.7 14493.6 14574.8 14602.9 14633.3 14634.6 10799.3 11008.4 12638.7 12656.6 12659.6 11082.7 12472.0 12667.8 12791.7 12905.8 4582.3 4672.3 4921.2 5584.3 5648.8 1075.5 1119.5 1151.0 1171.1 1219.7 27626.4 27631.0 28088.9 28099.6 28267.6 835.0 865.4 1157.1 1195.6 1307.6 12386.1 13144.8 13221.5 13231.3 13294.3 269573.0 270238.0 296216.5 296278.0 296722.0 35490.6 43067.1 43456.6 43529.3 52623.0 1465.2 1468.4 1477.4 1486.2 1487.3 27650.3 28990.3 29127.1 29676.0 29725.0 43909.8 44049.0 44060.6 44067.6 44177.7 252553.0 255591.7 297221.0 297359.0 297768.5 28969.7 32285.8 33143.7 33149.3 33596.3 41478.2 41664.8 41808.2 42336.8 42444.6 28131.0 29052.0 29360.0 30001.8 30057.0 28270.7 28790.6 29003.6 29914.1 30111.5 33550.0 34672.0 34686.5 34688.7 34761.3 28401.9 29299.4 29317.9 29337.0 29997.4 22201.4 22861.0 23172.4 23581.9 23686.9 08:08:49 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:41931.97 Median:21779.96 stddev:121314.38 (557.0%) stddev-sans-first:121614.67 08:08:49 INFO - PROCESS | 1637 | Values: 425.3 433.9 435.6 438.3 444.8 19340.9 20619.0 21666.1 21893.8 21979.9 19480.9 19657.8 21222.6 22318.5 23151.5 6303.9 6990.1 7004.2 7015.5 7075.4 6688.7 6781.6 6859.5 6917.3 6978.0 6584.3 6870.6 6988.0 7004.6 7005.6 6851.2 6939.5 6942.8 6957.0 6992.4 6435.2 6764.0 6831.4 6837.2 6838.0 24949.0 26022.2 26872.3 26883.2 27744.7 2883.2 2921.4 2957.6 2991.1 2993.9 15534.5 15665.1 15686.5 16108.4 16457.8 1994.4 2039.9 2042.2 2061.4 2105.6 158359.2 161555.0 161622.0 162450.0 163429.4 14983.8 19207.8 19862.5 20059.8 20096.5 32195.0 32709.5 32743.8 32760.0 32789.5 9442.8 9647.8 9833.4 10009.2 10028.3 7283.8 7342.6 8096.6 8104.1 8237.8 7751.8 8983.1 9279.2 9351.4 9352.6 2911.5 3447.8 3600.6 3652.2 3759.7 953.0 1051.3 1070.8 1078.4 1093.0 21631.1 22086.3 22109.8 22121.8 22200.7 1015.6 1092.0 1096.5 1118.1 1122.4 9101.0 10840.9 11429.9 11507.4 11527.2 409193.9 767502.0 783786.0 784470.0 792532.0 147142.0 158293.0 159023.0 161684.6 163929.2 1221.3 1230.2 1276.9 1281.7 1287.2 15693.3 20004.9 20156.0 20716.5 20746.7 47883.1 53675.4 57192.4 58246.4 60785.7 82350.9 82449.6 86942.6 88089.0 91646.0 10671.4 11208.8 11395.9 11522.5 11925.7 12555.7 12614.7 12677.5 12859.7 12881.4 10830.6 10973.5 11154.7 11176.3 11305.2 10214.7 10256.8 10295.3 10410.5 10611.3 9548.3 11153.8 11217.3 11371.7 11532.6 11285.3 11400.0 11401.2 11749.5 11840.7 10979.4 10985.6 10992.2 10995.9 11259.8 08:08:49 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6761.22 Median:2448.16 stddev:4274.50 (174.6%) stddev-sans-first:4267.22 08:08:49 INFO - PROCESS | 1637 | Values: 1359.4 1593.2 1619.8 1623.1 1631.6 9399.7 9816.9 11075.2 11391.5 11510.7 11235.9 11282.4 11322.2 11333.6 11334.7 2021.8 2376.7 2421.7 2423.6 2429.5 2007.9 2094.7 2155.3 2373.7 2461.0 2396.0 2428.9 2431.0 2432.0 2434.0 2395.4 2424.7 2430.7 2430.9 2435.3 2350.4 2358.0 2369.0 2373.4 2382.0 11319.2 11354.1 11370.8 11372.0 11433.7 5824.1 6029.6 6038.9 6045.8 6049.9 3191.2 3404.7 3482.7 3487.1 3488.3 1077.3 1114.2 1144.2 1153.0 1164.7 15060.3 15506.2 15565.4 15745.7 15810.9 4555.4 4562.7 4671.4 4674.3 4744.2 11888.5 11937.3 11946.1 11966.3 11966.9 5853.0 5853.6 5873.3 5874.2 5908.6 4434.7 4708.9 5097.3 5216.4 5374.6 3248.5 3344.7 3791.9 3836.3 3882.2 991.8 1003.9 1022.0 1022.7 1027.2 737.8 837.0 848.6 856.4 859.5 6365.9 6503.7 6714.0 6817.8 6904.1 1053.2 1068.4 1070.6 1097.4 1145.8 3710.5 4178.5 4314.9 4360.2 4477.3 13205.0 13980.9 13989.9 14005.9 14017.4 13721.9 13856.9 13878.1 13889.2 13907.8 9952.4 10385.6 10497.2 10498.5 10512.3 6945.5 6963.1 6984.4 7017.2 7178.2 12086.9 12320.2 12772.8 12801.9 12841.4 9758.5 11999.6 12068.1 12296.6 12623.9 7688.4 9330.4 9362.0 9479.3 9576.1 8962.8 9227.7 9340.4 10202.8 10297.5 8582.4 8680.3 8722.9 9161.0 9197.4 8568.2 8634.0 8751.0 8852.0 8872.2 8058.6 8061.4 8168.0 8169.7 8178.9 5293.3 5940.2 5993.5 6171.4 6231.8 5966.1 5966.9 6168.5 6171.9 6230.8 08:08:49 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4178.92 Median:4444.21 stddev:1158.87 (26.1%) stddev-sans-first:1125.03 08:08:49 INFO - PROCESS | 1637 | Values: 303.8 368.2 381.0 384.0 390.4 3804.3 3831.0 4831.4 4871.3 4968.8 3904.9 4182.6 4263.5 4409.9 4540.8 3600.8 3612.5 3850.2 3957.4 4090.7 3474.5 3853.3 4012.9 4110.5 4122.4 3553.6 3733.4 3918.1 4091.0 4096.8 3751.2 3800.2 3966.8 3988.3 4016.0 3418.6 3702.7 3705.2 3743.3 3759.4 3781.2 4320.5 4677.8 4699.7 4745.3 3944.4 4275.8 4517.2 4601.0 4638.6 4541.8 4904.1 5063.5 5101.5 5118.4 3500.4 3561.6 3578.9 4037.0 4164.3 4389.9 4593.9 5005.7 5410.2 5486.2 3641.7 4047.2 4200.6 4201.9 4220.4 3815.7 4006.0 4246.2 4280.5 4297.8 3797.9 3877.9 4192.9 4644.2 4666.2 4209.8 4839.6 5090.7 5109.2 5142.2 1342.5 1400.9 1447.8 1456.6 1480.7 954.9 1002.4 1103.3 1114.7 1121.5 4113.4 4196.4 4580.3 4591.5 4646.4 4427.4 4678.9 5209.3 5295.7 5301.7 3859.5 3951.6 4265.8 4511.0 4593.5 2348.8 2671.2 2733.4 2744.6 2761.3 4767.9 4865.2 4901.0 5179.9 5512.5 3942.0 4670.3 5294.3 5389.6 5440.6 4594.6 4797.2 5377.4 5410.5 5482.6 4710.3 4734.6 4900.8 4998.9 5255.1 4309.5 4427.4 5193.9 5208.7 5241.1 5016.4 5130.3 5232.9 5248.8 5499.4 3783.7 3918.3 4039.0 4115.0 4195.9 4786.8 5086.4 5173.8 5277.1 5308.9 4714.0 5143.7 5167.0 5176.9 5194.0 4441.6 4619.6 4884.5 5103.9 5137.8 4127.0 4719.9 4989.7 5027.6 5028.3 4395.1 4601.4 4906.0 5077.3 5078.3 4446.9 4753.4 4757.2 4832.7 5002.6 08:08:49 INFO - PROCESS | 1637 | 08:08:49 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19683.00 Median:12878.71 stddev:27525.82 (213.7%) stddev-sans-first:27566.21 08:08:49 INFO - PROCESS | 1637 | Values: 719.8 766.5 774.6 845.2 855.6 15231.3 15928.2 16022.5 16055.8 16082.1 14442.6 14825.9 14900.7 14960.3 15253.3 956.1 992.9 995.4 997.9 998.3 990.1 994.6 995.0 999.1 999.1 897.7 1013.9 1051.7 1052.1 1052.7 1036.3 1036.4 1036.5 1036.9 1038.3 764.3 768.4 768.7 770.1 770.2 15823.8 16210.1 16261.8 16765.7 16933.4 14346.5 14404.2 14424.8 14434.7 14471.9 11127.6 11155.5 11215.7 11283.7 11369.1 3670.9 3682.4 3709.1 3801.5 3920.4 49730.8 84500.0 96820.5 111784.4 112145.0 10535.7 10878.6 10894.4 11240.8 11426.7 21657.7 22003.8 22030.9 22488.2 22518.1 7957.9 8015.0 8023.4 8051.3 8059.0 4191.0 4243.1 4271.2 4302.8 4410.2 3239.3 3352.6 3405.4 3442.1 3514.6 571.5 572.1 573.1 578.0 585.9 614.4 708.0 718.0 725.8 742.4 10270.2 10420.6 10468.6 10532.6 10533.7 680.5 682.3 714.1 726.6 744.0 4661.7 4845.7 5000.0 5083.5 5116.2 80253.6 105340.5 106554.9 107051.0 108509.0 64390.7 67803.0 79366.8 90049.5 91027.5 75407.6 76425.4 76979.6 78727.0 79008.6 9080.0 9453.5 10815.3 11169.3 11208.5 44379.1 46090.0 49030.0 50773.2 51342.7 57989.7 63086.0 64113.0 64980.0 65946.0 12876.6 12924.0 13126.8 13209.4 13275.1 14711.3 14815.7 14875.8 15062.7 15199.6 13651.6 13893.3 14026.4 14290.2 14531.9 11173.8 13521.3 13937.9 14134.9 14405.4 12787.6 12853.7 13658.7 13673.5 13860.7 8003.6 9971.2 10245.0 10326.8 10373.5 10847.7 11143.6 11325.8 11577.8 11601.3 08:08:49 INFO - PROCESS | 1637 | -------- Summary: end -------- 08:08:49 INFO - PROCESS | 1637 | 08:08:50 INFO - TEST-INFO | 1637: exit 0 08:08:50 INFO - TEST-OK | dromaeo_css | took 1118919ms 08:08:50 INFO - TEST-START | kraken 08:08:50 INFO - operating with platform_type : mac_ 08:08:50 INFO - Initialising browser for kraken test... 08:08:50 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpQWWCCu/profile http://localhost:49217/getInfo.html) 08:08:52 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 08:08:52 INFO - PROCESS | 1646 | colorDepth:24 08:08:52 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 08:08:52 INFO - PROCESS | 1646 | __metrics 08:08:52 INFO - PROCESS | 1646 | 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" 08:08:52 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:08:52 INFO - TEST-INFO | 1646: exit 0 08:08:52 INFO - Browser initialized. 08:08:52 INFO - Running cycle 1/1 for kraken test... 08:08:52 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpQWWCCu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:05 INFO - PROCESS | 1648 | RSS: Main: 193777664 08:09:05 INFO - PROCESS | 1648 | 08:09:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49217/tests/kraken/driver.html?testName=audio-beat-detection) 08:09:08 INFO - PROCESS | 1648 | RSS: Main: 244592640 08:09:08 INFO - PROCESS | 1648 | 08:09:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49217/tests/kraken/driver.html?testName=audio-dft) 08:09:11 INFO - PROCESS | 1648 | RSS: Main: 258613248 08:09:11 INFO - PROCESS | 1648 | 08:09:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49217/tests/kraken/driver.html?testName=audio-fft) 08:09:15 INFO - PROCESS | 1648 | RSS: Main: 355643392 08:09:15 INFO - PROCESS | 1648 | 08:09:17 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49217/tests/kraken/driver.html?testName=audio-oscillator) 08:09:18 INFO - PROCESS | 1648 | RSS: Main: 257961984 08:09:18 INFO - PROCESS | 1648 | 08:09:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49217/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:09:20 INFO - PROCESS | 1648 | RSS: Main: 259207168 08:09:20 INFO - PROCESS | 1648 | 08:09:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49217/tests/kraken/driver.html?testName=imaging-darkroom) 08:09:24 INFO - PROCESS | 1648 | RSS: Main: 300027904 08:09:24 INFO - PROCESS | 1648 | 08:09:27 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49217/tests/kraken/driver.html?testName=imaging-desaturate) 08:09:27 INFO - PROCESS | 1648 | RSS: Main: 301527040 08:09:27 INFO - PROCESS | 1648 | 08:09:30 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49217/tests/kraken/driver.html?testName=json-parse-financial) 08:09:31 INFO - PROCESS | 1648 | RSS: Main: 300015616 08:09:31 INFO - PROCESS | 1648 | 08:09:33 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49217/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:09:33 INFO - PROCESS | 1648 | RSS: Main: 276279296 08:09:33 INFO - PROCESS | 1648 | 08:09:35 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49217/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:09:36 INFO - PROCESS | 1648 | RSS: Main: 280842240 08:09:36 INFO - PROCESS | 1648 | 08:09:38 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49217/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:09:39 INFO - PROCESS | 1648 | RSS: Main: 284655616 08:09:39 INFO - PROCESS | 1648 | 08:09:42 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49217/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:09:42 INFO - PROCESS | 1648 | RSS: Main: 290004992 08:09:42 INFO - PROCESS | 1648 | 08:09:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49217/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:09:46 INFO - PROCESS | 1648 | RSS: Main: 290795520 08:09:46 INFO - PROCESS | 1648 | 08:09:47 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49217/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49217/tests/kraken/driver.html?testName=ai-astar) 08:09:48 INFO - PROCESS | 1648 | RSS: Main: 335491072 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | __start_tp_report 08:09:48 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 08:09:48 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 08:09:48 INFO - PROCESS | 1648 | |i|pagename|runs| 08:09:48 INFO - PROCESS | 1648 | |0;ai-astar;105;117;117;117;115;118;116;116;116;117 08:09:48 INFO - PROCESS | 1648 | |1;audio-beat-detection;139;109;106;141;111;109;110;107;126;108 08:09:48 INFO - PROCESS | 1648 | |2;audio-dft;186;187;184;185;183;186;182;185;183;185 08:09:48 INFO - PROCESS | 1648 | |3;audio-fft;83;60;61;94;63;73;50;51;89;84 08:09:48 INFO - PROCESS | 1648 | |4;audio-oscillator;93;70;87;70;69;69;83;69;69;84 08:09:48 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;88;86;87;87;86;86;87;87;87;86 08:09:48 INFO - PROCESS | 1648 | |6;imaging-darkroom;94;94;94;95;94;94;95;95;93;94 08:09:48 INFO - PROCESS | 1648 | |7;imaging-desaturate;84;84;86;86;86;85;86;98;138;140 08:09:48 INFO - PROCESS | 1648 | |8;json-parse-financial;62;62;62;62;62;63;63;64;62;62 08:09:48 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;55;62;64;65;61;59;59;58;57;58 08:09:48 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;82;82;85;86;82;84;84;85;89;109 08:09:48 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;139;138;145;144;132;136;136;141;149;137 08:09:48 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;169;160;170;168;167;170;167;167;166;166 08:09:48 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;59;81;82;66;65;64;67;63;64;64 08:09:48 INFO - PROCESS | 1648 | __end_tp_report 08:09:48 INFO - PROCESS | 1648 | __start_cc_report 08:09:48 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2068 08:09:48 INFO - PROCESS | 1648 | __end_cc_report 08:09:48 INFO - PROCESS | 1648 | __startTimestamp1495638588125__endTimestamp 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | ------- Summary: start ------- 08:09:48 INFO - PROCESS | 1648 | Number of tests: 14 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:115.40 Median:117.00 stddev:3.75 (3.2%) stddev-sans-first:0.88 08:09:48 INFO - PROCESS | 1648 | Values: 105.0 117.0 117.0 117.0 115.0 118.0 116.0 116.0 116.0 117.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:116.60 Median:110.50 stddev:13.56 (12.3%) stddev-sans-first:11.71 08:09:48 INFO - PROCESS | 1648 | Values: 139.0 109.0 106.0 141.0 111.0 109.0 110.0 107.0 126.0 108.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:1.58 (0.9%) stddev-sans-first:1.59 08:09:48 INFO - PROCESS | 1648 | Values: 186.0 187.0 184.0 185.0 183.0 186.0 182.0 185.0 183.0 185.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:70.80 Median:78.00 stddev:15.97 (20.5%) stddev-sans-first:16.32 08:09:48 INFO - PROCESS | 1648 | Values: 83.0 60.0 61.0 94.0 63.0 73.0 50.0 51.0 89.0 84.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:76.50 stddev:9.37 (12.2%) stddev-sans-first:7.75 08:09:48 INFO - PROCESS | 1648 | Values: 93.0 70.0 87.0 70.0 69.0 69.0 83.0 69.0 69.0 84.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:0.67 (0.8%) stddev-sans-first:0.53 08:09:48 INFO - PROCESS | 1648 | Values: 88.0 86.0 87.0 87.0 86.0 86.0 87.0 87.0 87.0 86.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 08:09:48 INFO - PROCESS | 1648 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 95.0 95.0 93.0 94.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:86.00 stddev:22.35 (26.0%) stddev-sans-first:23.18 08:09:48 INFO - PROCESS | 1648 | Values: 84.0 84.0 86.0 86.0 86.0 85.0 86.0 98.0 138.0 140.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:62.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 08:09:48 INFO - PROCESS | 1648 | Values: 62.0 62.0 62.0 62.0 62.0 63.0 63.0 64.0 62.0 62.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:3.16 (5.3%) stddev-sans-first:2.83 08:09:48 INFO - PROCESS | 1648 | Values: 55.0 62.0 64.0 65.0 61.0 59.0 59.0 58.0 57.0 58.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.50 stddev:8.09 (9.6%) stddev-sans-first:8.40 08:09:48 INFO - PROCESS | 1648 | Values: 82.0 82.0 85.0 86.0 82.0 84.0 84.0 85.0 89.0 109.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:140.00 stddev:5.08 (3.6%) stddev-sans-first:5.38 08:09:48 INFO - PROCESS | 1648 | Values: 139.0 138.0 145.0 144.0 132.0 136.0 136.0 141.0 149.0 137.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.50 stddev:2.87 (1.7%) stddev-sans-first:2.95 08:09:48 INFO - PROCESS | 1648 | Values: 169.0 160.0 170.0 168.0 167.0 170.0 167.0 167.0 166.0 166.0 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:65.50 stddev:7.68 (11.7%) stddev-sans-first:7.50 08:09:48 INFO - PROCESS | 1648 | Values: 59.0 81.0 82.0 66.0 65.0 64.0 67.0 63.0 64.0 64.0 08:09:48 INFO - PROCESS | 1648 | -------- Summary: end -------- 08:09:48 INFO - PROCESS | 1648 | 08:09:48 INFO - TEST-INFO | 1648: exit 0 08:09:48 INFO - TEST-OK | kraken | took 58756ms 08:09:48 INFO - SUITE-END | took 1177s 08:09:49 INFO - Completed test suite (00:19:38) 08:09:49 INFO - javascript benchmark 08:09:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7510.578596724988, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.6003279606443, 3337.9151775672904, 3338.949915751264, 3366.0, 3383.390989621869, 5576.414476479972, 5694.857628559288, 5698.943010569893, 5712.914306285409, 5741.224934633823, 5629.268195134637, 5735.139729040646, 5745.109508026263, 5759.366469688342, 5777.4511421415, 5163.044836705203, 5211.45279745627, 5228.686278823264, 5242.397124330693, 5276.234945932837, 5136.922946155811, 5168.715720635363, 5216.26972223889, 5229.686218826863, 5243.9213411798855, 5301.655392399501, 5303.814366497173, 5305.363356397238, 5315.548178404821, 5347.0909945309195, 5313.6014798889855, 5322.92015619766, 5327.786888524473, 5334.759935802898, 5381.5425688816495, 4458.799354029076, 4969.378827646544, 5054.393472783253, 5180.663256888291, 5286.3127793387, 5603.187537807036, 5621.690807005613, 5625.71871406428, 5676.489115979541, 5680.6591604503865, 6268.279147898004, 6287.622742635411, 6301.59039662425, 6319.431251187415, 6339.619622822599, 5207.297014902978, 6344.889644312273, 6345.8413539661415, 6350.015747559136, 6353.396427339396, 5609.214709940612, 5651.378348381675, 5713.685747283939, 5797.797077102281, 5821.767129314865, 6309.968450157811, 6318.241810982619, 6321.873562528724, 6333.556651034383, 6339.619622822645, 6059.091136329585, 6118.265808103056, 6119.694015299307, 6130.141780150782, 6134.754609815557, 6221.96889015552, 6300.905486417708, 6314.17915670969, 6315.210598675166, 6317.3366796486625, 6008.549358798107, 6249.312575616678, 6254.21822272216, 6258.530610204253, 6258.593191442542, 6147.877042459126, 6152.692365381802, 6162.014077747539, 6194.0, 6209.285932117774, 4775.450823155382, 4803.615710743132, 4804.8798780030775, 4806.44725856524, 4835.468098509161, 3556.8043757593064, 4273.93151712072, 4289.378040184219, 4297.914041719148, 4330.35044743291, 5369.382521010056, 5400.783968641211, 5479.808206712746, 5572.0, 5588.748506317144, 6202.596831205957, 6212.844678883064, 6223.1287619733275, 6237.03325984464, 6242.687865606701, 5602.243697100839, 5632.464915832989, 5634.464725851038, 5644.294463192101, 5660.490555849993, 5379.058664733734, 5417.106177480671, 5420.891582168413, 5428.4028756836715, 5445.0, 6391.392817682313, 6399.488040956805, 6402.10370548133, 6411.551191416649, 6433.067205255305, 6111.07520520229, 6145.723442445012, 6291.307956124731, 6341.682915854281, 6388.776392826228, 6383.96808015957, 6439.581427207404, 6440.871182576228, 6463.353664633499, 6467.0, 6280.811575652738, 6283.96858015707, 6300.621962682254, 6304.810855674337, 6321.8735625288155, 6122.428649698624, 6149.200603921461, 6216.937830621636, 6264.091706702594, 6333.20834895638, 4833.540813622701, 6138.508919286535, 6190.0, 6192.721327540181, 6193.566450348432, 6197.349278325892, 6209.410106039919, 6211.751529938932, 6227.159333489921, 6239.0953311770445, 5802.390748971466, 5824.475797178275, 6003.189569408074, 6004.39956004396, 6048.969755151372, 6132.998044146438, 6137.8979746733885, 6165.691715414122, 6183.196184495987, 6196.969015154896, 5996.938917489954, 6011.819645410645, 6020.488258498077, 6060.939390606038, 6086.878262434638], "value": 5689.96002967925, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.0, 795.4908858330667, 816.6039470856635, 882.7131182365732, 895.225629830197, 28887.42225155498, 40588.0, 41268.793656031754, 41340.79329603355, 41421.96445088871, 42322.57677423225, 42499.57500424995, 42512.57487425125, 42553.148937021244, 42565.787171064105, 7478.663460144279, 9407.965123836373, 9552.474613896233, 9569.712908612753, 9583.760405989837, 7570.12943511495, 11322.830157547643, 11566.942165289203, 11676.241044332133, 11700.00549953255, 11524.481398337095, 11545.01867341277, 11564.653060408202, 11574.016208622277, 11601.767964640701, 11475.368854712988, 11481.483333250024, 11494.36780977046, 11507.194496385257, 11510.021648159875, 11227.045701115416, 11266.380349080797, 11280.2667826591, 11286.097112231084, 11288.94355528217, 38939.61060389388, 39094.80452597747, 39120.80439597784, 39143.82568522949, 39193.80403097995, 3809.276497143522, 3853.8651147209994, 3894.084890050836, 3896.766194028339, 3953.4069889516513, 27680.584791228146, 27791.16626501208, 29169.41661166786, 29938.0, 30032.699673003208, 2972.4946759051013, 3143.465610846162, 3237.223066464055, 3253.6746325367276, 3258.413485572596, 330823.0, 333185.0, 333429.0, 334166.0, 334234.0, 53254.46745532573, 53527.73236133794, 53559.732201338746, 53625.731871340395, 53625.92748145093, 55642.44357556454, 55758.0, 55816.441835581936, 55823.44176558264, 55953.72023139858, 14493.565193044014, 14574.781378279335, 14602.85397146015, 14633.341499632543, 14634.634134146732, 10799.298045627009, 11008.449577521253, 12638.684032899251, 12656.620301390973, 12659.556915507914, 11082.667519974413, 12472.0, 12667.809982850265, 12791.744165116646, 12905.806412903814, 4582.289745089516, 4672.345871577982, 4921.212605983025, 5584.329880414376, 5648.774049038075, 1075.5052675769234, 1119.4962266979728, 1150.9942450287863, 1171.1333613126226, 1219.737756382382, 27626.44747105087, 27631.0, 28088.8595557025, 28099.57850632242, 28267.57598636022, 834.9527108628356, 865.4253595553179, 1157.062779148892, 1195.647284051207, 1307.6207899709168, 12386.132970692146, 13144.802827957588, 13221.471141154247, 13231.2722800245, 13294.335283235992, 269573.0, 270238.0, 296216.51891740836, 296278.0, 296722.0, 35490.645093549254, 43067.138657227304, 43456.565434345255, 43529.34705979413, 52623.0, 1465.2014652014677, 1468.3539242733164, 1477.3795006097464, 1486.1603194195245, 1487.315834716021, 27650.308742281602, 28990.27524311909, 29127.12618621445, 29675.961341352948, 29724.959626412972, 43909.78045109754, 44049.0, 44060.559394405645, 44067.55932440634, 44177.67466975996, 252553.0, 255591.7220413886, 297221.0, 297359.0, 297768.5111574515, 28969.7103028967, 32285.838570806995, 33143.67425302961, 33149.337013259115, 33596.32807343888, 41478.170436590495, 41664.79167604142, 41808.1638367237, 42336.78831605822, 42444.5755542453, 28131.0, 29051.983180588577, 29359.972400965864, 30001.849990749906, 30057.0, 28270.71729282681, 28790.568141478314, 29003.56494652624, 29914.102576922727, 30111.54832677468, 33549.993500195036, 34671.95984120481, 34686.47970280498, 34688.65311346854, 34761.304773904885, 28401.857990709916, 29299.41401171922, 29317.85341073281, 29337.0, 29997.400051999273, 22201.44496387603, 22861.0, 23172.420689482897, 23581.88209058944, 23686.934087966427], "value": 18233.459203003902, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.330105084492, 433.8763452416061, 435.6188335206694, 438.3490516582874, 444.8265176581134, 19340.90329548354, 20619.0, 21666.133354665795, 21893.781062189413, 21979.890100549495, 19480.902595487038, 19657.80342196581, 21222.62052966556, 22318.54929429586, 23151.536969260604, 6303.905441418371, 6990.091288132536, 7004.229534751174, 7015.473839462035, 7075.363217310441, 6688.665566721645, 6781.627010514419, 6859.519833611649, 6917.342852429011, 6978.0, 6584.341565843425, 6870.553414028072, 6987.965060174692, 7004.614746188958, 7005.649717514129, 6851.246362900077, 6939.514234003622, 6942.791716248521, 6957.0, 6992.440604751608, 6435.163428754278, 6764.0, 6831.419329357011, 6837.213720422165, 6838.00848876915, 24949.00203991838, 26022.219333420027, 26872.328191795164, 26883.193504194904, 27744.722552774416, 2883.2215301868587, 2921.4449254641763, 2957.5859379686863, 2991.1026691992315, 2993.925151871199, 15534.456294029767, 15665.13841738704, 15686.52940411778, 16108.436204732896, 16457.83542164575, 1994.3518356534184, 2039.8980050997538, 2042.234162189179, 2061.4331058958755, 2105.5788842231614, 158359.2082039594, 161554.99999999884, 161622.0, 162450.0, 163429.3657063426, 14983.775243371358, 19207.751496152985, 19862.5309305408, 20059.799402005792, 20096.49758756043, 32195.0, 32709.509357359657, 32743.836280818443, 32760.0, 32789.52447139884, 9442.811143777086, 9647.807043859084, 9833.360831545926, 10009.249306302056, 10028.348157369748, 7283.781486555412, 7342.559446433231, 8096.595170241464, 8104.149064348277, 8237.79405514867, 7751.844963100706, 8983.101689831095, 9279.164875161268, 9351.392159509745, 9352.579133938989, 2911.505044142501, 3447.7586568940437, 3600.6039335673054, 3652.19651676636, 3759.7180211484247, 952.9618815247312, 1051.3218973762034, 1070.817960946641, 1078.412265315405, 1092.9508172132273, 21631.134754609953, 22086.337409877728, 22109.778902211092, 22121.77878221197, 22200.666989995163, 1015.5683834370363, 1091.956321747137, 1096.5010920031366, 1118.105515587543, 1122.421952694363, 9101.0, 10840.891591084146, 11429.942850285861, 11507.367094809699, 11527.193096483166, 409193.90806092153, 767502.0, 783786.0, 784470.0, 792532.0, 147142.0, 158293.0, 159023.0, 161684.57473137914, 163929.18035409748, 1221.3465795799204, 1230.2249582762802, 1276.9465191217282, 1281.705207802192, 1287.2469605280828, 15693.293801778718, 20004.89997550003, 20156.0, 20716.482087947923, 20746.688799668318, 47883.127012379366, 53675.38973830792, 57192.42807571871, 58246.4175358241, 60785.696071519356, 82350.94122646742, 82449.58775206086, 86942.56528717316, 88089.0, 91646.0, 10671.35971841692, 11208.831867522156, 11395.943020284845, 11522.481488332878, 11925.701857453632, 12555.686107847378, 12614.747705046031, 12677.477226758123, 12859.678508037374, 12881.35593220354, 10830.566777328819, 10973.451327433437, 11154.721131971766, 11176.27354221973, 11305.15211359135, 10214.744631384276, 10256.794864102527, 10295.279330447096, 10410.531526081497, 10611.31026483276, 9548.331616786758, 11153.776924461303, 11217.326960382403, 11371.658850234506, 11532.59635912739, 11285.322880627189, 11400.0, 11401.20191586581, 11749.530018799494, 11840.70398240051, 10979.39613321275, 10985.560577576807, 10992.230543861853, 10995.945020274847, 11259.831102533304], "value": 11489.901244110752, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.3814814259513, 1593.1874743880624, 1619.829917858625, 1623.0667366264393, 1631.6165701060252, 9399.67101151459, 9816.901830981687, 11075.16936229782, 11391.48738306776, 11510.654680359603, 11235.943820280887, 11282.379469129195, 11322.15083868711, 11333.603323883666, 11334.716632084203, 2021.8079282468143, 2376.714794224691, 2421.7457166997474, 2423.587990041697, 2429.4898071404937, 2007.8694884832512, 2094.748630164375, 2155.267209148889, 2373.738888722239, 2461.027893981876, 2395.9760402396014, 2428.86641234732, 2431.0397393029743, 2432.0, 2434.0385547708643, 2395.4490467192554, 2424.733279339271, 2430.6597076409316, 2430.902763889442, 2435.2937648082034, 2350.4476448034698, 2357.976420235793, 2369.0, 2373.41851246444, 2381.9995601847268, 11319.20765546412, 11354.091672666167, 11370.829437558443, 11372.03337716295, 11433.656990290221, 5824.126381042835, 6029.6382217066675, 6038.939610603882, 6045.818625441244, 6049.879002419927, 3191.1862475068942, 3404.676555727202, 3482.66914643111, 3487.0584942065752, 3488.3023395320783, 1077.2728408324429, 1114.186643750059, 1144.193343692703, 1153.0314535789976, 1164.673891310426, 15060.32228549718, 15506.22468876563, 15565.37738490459, 15745.68508629821, 15810.92094539543, 4555.362249285103, 4562.703424277478, 4671.392718946515, 4674.252119660838, 4744.169770290186, 11888.524459021715, 11937.28376297425, 11946.10404219687, 11966.282023078644, 11966.940165299118, 5853.0, 5853.590248682636, 5873.324567674772, 5874.206982057453, 5908.615939963975, 4434.711743736647, 4708.882277943078, 5097.337346145052, 5216.37403511581, 5374.596905232123, 3248.480243161083, 3344.7156991655984, 3791.9431208531896, 3836.328642487609, 3882.2041481496226, 991.8264303746815, 1003.8845532763826, 1021.9897801022043, 1022.6522982186092, 1027.173125633872, 737.8155461134717, 836.9748907532784, 848.6138806842872, 856.4433118473042, 859.5229647545672, 6365.936340636627, 6503.674816259168, 6714.026466162379, 6817.761378351734, 6904.102466679299, 1053.2311412668794, 1068.407034096084, 1070.5985255529176, 1097.4128841070149, 1145.8395824584566, 3710.4805327254207, 4178.456800615901, 4314.935275970863, 4360.236958532244, 4477.261251893467, 13205.0, 13980.860191397956, 13989.86010139906, 14005.929970350084, 14017.369218384993, 13721.931390342985, 13856.861431385558, 13878.097923634932, 13889.16665000083, 13907.79138312926, 9952.402855828674, 10385.584576617002, 10497.212709047004, 10498.527566259536, 10512.26414151002, 6945.482446962232, 6963.094797676472, 6984.40632546219, 7017.193022802239, 7178.210396856446, 12086.879131208576, 12320.199187052813, 12772.808407873703, 12801.93599031999, 12841.422136003715, 9758.46328451942, 11999.580014699444, 12068.094892882893, 12296.569620063256, 12623.93688031554, 7688.384929205538, 9330.393524420891, 9362.016988216139, 9479.336446448682, 9576.138147566753, 8962.775930601787, 9227.677031303872, 9340.439573625605, 10202.846957295795, 10297.485125743533, 8582.356323275904, 8680.305575554066, 8722.912770872465, 9160.954195228982, 9197.448153110834, 8568.186022327869, 8634.026402805002, 8750.956245218733, 8852.02204054016, 8872.201501864864, 8058.556779377191, 8061.355091592776, 8168.0, 8169.714060007871, 8178.918210817816, 5293.311869456946, 5940.1683764272175, 5993.460588547052, 6171.444569988723, 6231.781887633911, 5966.134910438049, 5966.910496342645, 6168.475679567287, 6171.876562468815, 6230.7819226326865], "value": 5119.398882019347, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.79190254675547, 368.2193749251587, 380.9980950095249, 383.99999999999983, 390.3792969179005, 3804.2771873344045, 3831.0, 4831.444383895853, 4871.295046733641, 4968.751562421878, 3904.910872683115, 4182.602652747984, 4263.467066616673, 4409.889752756183, 4540.84208526826, 3600.8132802469872, 3612.4647025014397, 3850.191459793447, 3957.4261732048835, 4090.713650044498, 3474.496198051281, 3853.287141878747, 4012.8996775080564, 4110.458578033237, 4122.443470131524, 3553.5608078728155, 3733.383991641376, 3918.1380096378894, 4090.9590904091024, 4096.836126554934, 3751.212245428463, 3800.239952009609, 3966.7619942803235, 3988.282109220339, 4016.0, 3418.6239513653477, 3702.685271751904, 3705.184859330943, 3743.288775132716, 3759.4172903200047, 3781.220802710006, 4320.503142138631, 4677.836275730333, 4699.718016918996, 4745.311929770199, 3944.3653706894283, 4275.8075886585175, 4517.226415000248, 4600.9769951150365, 4638.6342965087715, 4541.772911354419, 4904.131083316264, 5063.493650634907, 5101.51535604117, 5118.385793704742, 3500.3524347995703, 3561.626029266916, 3578.8926332210076, 4036.979815100906, 4164.250434921712, 4389.890252743707, 4593.885152871205, 5005.6996580205305, 5410.213739923943, 5486.231927530109, 3641.7450778445254, 4047.170330082326, 4200.613797446836, 4201.907504048908, 4220.388043733641, 3815.6947444204397, 4005.959940400617, 4246.171996460661, 4280.4863416390235, 4297.763623000703, 3797.930128080203, 3877.9335682686924, 4192.937105943413, 4644.164050470948, 4666.176962937442, 4209.800206941007, 4839.612830973514, 5090.720010399464, 5109.186238885142, 5142.228665700175, 1342.489853855548, 1400.859914008591, 1447.8117844680335, 1456.5798346611969, 1480.7112613040354, 954.9092836180552, 1002.3711468863097, 1103.2828661370013, 1114.7324642085841, 1121.506537123663, 4113.362428823537, 4196.3705444183615, 4580.335851301609, 4591.517890621503, 4646.442426908792, 4427.40230068943, 4678.929816052762, 5209.296744939385, 5295.655782374133, 5301.708406037628, 3859.459675645412, 3951.6245956634075, 4265.808038638207, 4511.030128522383, 4593.47175074864, 2348.7651234876716, 2671.1719366996294, 2733.412316351994, 2744.6157537944705, 2761.337279053013, 4767.856964291077, 4865.221564549726, 4900.99529596439, 5179.870503237449, 5512.531434828004, 3942.0289855072465, 4670.252759558454, 5294.258803767476, 5389.568834493231, 5440.591955603346, 4594.632429405639, 4797.160496913096, 5377.354717433933, 5410.458954104637, 5482.616216864861, 4710.340552322746, 4734.621230301636, 4900.828471003498, 4998.900021999612, 5255.132903071103, 4309.5259521453245, 4427.446569178853, 5193.870153246048, 5208.739563021758, 5241.082810508222, 5016.448190699093, 5130.333056702605, 5232.895342093213, 5248.845254044104, 5499.432661691559, 3783.7486695416183, 3918.3142949983067, 4038.9596104038583, 4114.9630932688515, 4195.8741237762915, 4786.808527658994, 5086.3896332440345, 5173.81891633791, 5277.1292736698015, 5308.946910530845, 4714.0, 5143.71709555978, 5167.018266529348, 5176.922346164735, 5193.974030129825, 4441.555844415532, 4619.5611416915335, 4884.48712885142, 5103.923441148311, 5137.794488220578, 4127.023476040533, 4719.882002949954, 4989.700617962933, 5027.59779217669, 5028.34631497903, 4395.1429471252795, 4601.363329920954, 4905.975470122769, 5077.339945807022, 5078.289039534542, 4446.8665940020555, 4753.407608451208, 4757.191277482905, 4832.661713680009, 5002.599792016557], "value": 3851.256997950485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.8236432074143, 766.4979438467802, 774.6010804435716, 845.1970627903493, 855.5716009574492, 15231.31459084341, 15928.203589820521, 16022.519324420271, 16055.75916361254, 16082.115483648395, 14442.638934026654, 14825.851373968851, 14900.7019859603, 14960.251987400614, 15253.31360088796, 956.1346980982222, 992.9056739609725, 995.4276291132592, 997.9201663866874, 998.3211416236956, 990.1187942730975, 994.5723338964242, 994.955227014786, 999.075854834279, 999.14073896449, 897.7082448204327, 1013.9188864890791, 1051.6950084475493, 1052.0636633396284, 1052.6578861869884, 1036.331566139843, 1036.4299635200648, 1036.486938965217, 1036.8703912011, 1038.2628333882876, 764.3006648916235, 768.4428789127893, 768.6925229908026, 770.0874463760406, 770.1912991359106, 15823.841761582353, 16210.108444035573, 16261.83738162615, 16765.74851377218, 16933.407330743365, 14346.497872574515, 14404.207768572724, 14424.783628245585, 14434.711305773826, 14471.855281447157, 11127.610533631285, 11155.52180342937, 11215.66353009411, 11283.717907052307, 11369.147313951404, 3670.944935825965, 3682.355587772156, 3709.1098136447326, 3801.467794508771, 3920.4315374270855, 49730.751346243036, 84500.0, 96820.51589742077, 111784.4410777941, 112144.99999999919, 10535.683929482127, 10878.564857405616, 10894.36952040338, 11240.831387529193, 11426.714332141762, 21657.67513487299, 22003.78979156162, 22030.88984555067, 22488.21291254798, 22518.099276029105, 7957.920420795834, 8015.0, 8023.398245131521, 8051.282562532968, 8059.032916050111, 4190.958090419118, 4243.130158317537, 4271.23117838792, 4302.849400271038, 4410.184115938561, 3239.319742854027, 3352.6479719629087, 3405.404054290489, 3442.087846720621, 3514.61339252682, 571.5456212311223, 572.0561074227558, 573.1231216239161, 577.9827503593706, 585.8857522783017, 614.4163045107166, 707.989380159298, 718.0, 725.836686745478, 742.4023660952985, 10270.178385729123, 10420.583176673, 10468.633597824191, 10532.631357902437, 10533.736656583493, 680.4825239715364, 682.2699711308951, 714.1358955663601, 726.564061563058, 743.9739609113655, 4661.743604101739, 4845.685030473079, 5000.0, 5083.517065878736, 5116.181410974301, 80253.59873200596, 105340.47329763303, 106554.9344506545, 107051.0, 108509.0, 64390.678046609464, 67802.9829552557, 79366.80949785758, 90049.54975225082, 91027.54486227658, 75407.62296188484, 76425.35746425313, 76979.61510192414, 78726.99999999885, 79008.60495697599, 9079.954600226956, 9453.480058596842, 10815.297005694605, 11169.329840209288, 11208.495617697386, 44379.11241775211, 46090.0, 49030.0, 50773.23840142327, 51342.74328628482, 57989.710051449474, 63086.0, 64113.0, 64980.0, 65946.01080983694, 12876.613701588967, 12924.030697697523, 13126.803097953347, 13209.405576749261, 13275.070745047755, 14711.3379897907, 14815.736927803395, 14875.776863346842, 15062.698746024798, 15199.620009499851, 13651.590452286448, 13893.305334733424, 14026.438942442595, 14290.214038227581, 14531.854681453473, 11173.7795012968, 13521.323933803074, 13937.93031034879, 14134.929325353307, 14405.351759170653, 12787.61637150887, 12853.742925141632, 13658.658533536343, 13673.521426750016, 13860.653483662989, 8003.559804210824, 9971.152452041657, 10245.0, 10326.793464130526, 10373.481325933522, 10847.67456976292, 11143.609973650884, 11325.773484530428, 11577.768444631229, 11601.303921764384], "value": 7636.2275907938065, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1425.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 117.0, 117.0, 115.0, 118.0, 116.0, 116.0, 116.0, 117.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 106.0, 141.0, 111.0, 109.0, 110.0, 107.0, 126.0, 108.0], "value": 116.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 184.0, 185.0, 183.0, 186.0, 182.0, 185.0, 183.0, 185.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 61.0, 94.0, 63.0, 73.0, 50.0, 51.0, 89.0, 84.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 70.0, 87.0, 70.0, 69.0, 69.0, 83.0, 69.0, 69.0, 84.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 87.0, 86.0, 86.0, 87.0, 87.0, 87.0, 86.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 93.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 86.0, 86.0, 85.0, 86.0, 98.0, 138.0, 140.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 63.0, 64.0, 62.0, 62.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 62.0, 64.0, 65.0, 61.0, 59.0, 59.0, 58.0, 57.0, 58.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 85.0, 86.0, 82.0, 84.0, 84.0, 85.0, 89.0, 109.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 145.0, 144.0, 132.0, 136.0, 136.0, 141.0, 149.0, 137.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 160.0, 170.0, 168.0, 167.0, 170.0, 167.0, 167.0, 166.0, 166.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 81.0, 82.0, 66.0, 65.0, 64.0, 67.0, 63.0, 64.0, 64.0], "value": 67.5, "unit": "score"}]}]} 08:09:49 INFO - Return code: 0 08:09:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:09:49 INFO - # TBPL SUCCESS # 08:09:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:09:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:09:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:09:49 INFO - 2017-05-24 08:09:49.457 screenresolution[1652:9793] starting screenresolution argv=screenresolution get 08:09:49 INFO - 2017-05-24 08:09:49.460 screenresolution[1652:9793] Display 0: 1600x1200x32@60 08:09:49 INFO - 2017-05-24 08:09:49.466 screenresolution[1653:9796] starting screenresolution argv=screenresolution list 08:09:49 INFO - Available Modes on Display 0 08:09:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:09:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:09:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:09:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:09:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:09:49 INFO - Intel Iris: 08:09:49 INFO - Chipset Model: Intel Iris 08:09:49 INFO - Type: GPU 08:09:49 INFO - Bus: Built-In 08:09:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:09:49 INFO - Vendor: Intel (0x8086) 08:09:49 INFO - Device ID: 0x0a2e 08:09:49 INFO - Revision ID: 0x0009 08:09:49 INFO - Displays: 08:09:49 INFO - Display: 08:09:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:09:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:09:49 INFO - Display Serial Number: 1600x1200 60 08:09:49 INFO - Main Display: Yes 08:09:49 INFO - Mirror: Off 08:09:49 INFO - Online: Yes 08:09:49 INFO - Rotation: Supported 08:09:49 INFO - Return code: 0 08:09:49 INFO - Running post-action listener: _resource_record_post_action 08:09:49 INFO - [mozharness: 2017-05-24 15:09:49.701859Z] Finished run-tests step (success) 08:09:49 INFO - Running post-run listener: _resource_record_post_run 08:09:49 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 40289280; Write bytes: 512011264; Read time: 388; Write time: 1648 08:09:49 INFO - TinderboxPrint: CPU usage
53.6% 08:09:49 INFO - TinderboxPrint: I/O read bytes / time
40,289,280 / 388 08:09:49 INFO - TinderboxPrint: I/O write bytes / time
512,011,264 / 1,648 08:09:49 INFO - TinderboxPrint: CPU idle
2,227.5 (46.4%) 08:09:49 INFO - TinderboxPrint: CPU system
955.5 (19.9%) 08:09:49 INFO - TinderboxPrint: CPU user
1,614.8 (33.7%) 08:09:49 INFO - TinderboxPrint: Swap in / out
248,102,912 / 0 08:09:49 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204694016; Write bytes: 204290048; Read time: 15651; Write time: 462 08:09:49 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37807104; Write bytes: 296920064; Read time: 358; Write time: 1151 08:09:50 INFO - Running post-run listener: _upload_blobber_files 08:09:50 INFO - Blob upload gear active. 08:09:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:09:50 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 08:09:50 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'] 08:09:50 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 08:09:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:09:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:09:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:09:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:51 INFO - (blobuploader) - INFO - Done attempting. 08:09:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:09:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:09:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:52 INFO - (blobuploader) - INFO - Done attempting. 08:09:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:09:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:09:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:53 INFO - (blobuploader) - INFO - Done attempting. 08:09:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:09:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:09:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:55 INFO - (blobuploader) - INFO - Done attempting. 08:09:55 INFO - (blobuploader) - INFO - Iteration through files over. 08:09:55 INFO - Return code: 0 08:09:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:09:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:09:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/130e32bc7a1bfa83b62a37d29c9ea6a101369aa32739c3b92e1bf2ea060650a669c039314de113038adf78b756ed0ec6891843c6837297294e8aaa4e78d8edca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f57c42f18b407e33e1b3274d9e57b81bbe54734245d2866724b0420c2feda6e58de5bc2a814443783747a3914da5d569ec15670fa4f249007313bf4a1b0d3bd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86ee4bc3ca72538d1149a3234d5d16ee33b6b8be43f833507f37225b7646be6b2b9b266a9aefab99cfcf333d14b7ba75be2f6e8ea078dcd074e23b55b08af200", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4ab7649382b91b3c2837eb4b5137785b435aa8c2e8aa0c9ce98bc06cce9d7559f3cdc9ea5dd3cffb13c30d27c28b88fbd308a74da6db3798e5b9eff8f843747"} 08:09:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:09:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:09:56 INFO - Contents: 08:09:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/130e32bc7a1bfa83b62a37d29c9ea6a101369aa32739c3b92e1bf2ea060650a669c039314de113038adf78b756ed0ec6891843c6837297294e8aaa4e78d8edca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f57c42f18b407e33e1b3274d9e57b81bbe54734245d2866724b0420c2feda6e58de5bc2a814443783747a3914da5d569ec15670fa4f249007313bf4a1b0d3bd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86ee4bc3ca72538d1149a3234d5d16ee33b6b8be43f833507f37225b7646be6b2b9b266a9aefab99cfcf333d14b7ba75be2f6e8ea078dcd074e23b55b08af200", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4ab7649382b91b3c2837eb4b5137785b435aa8c2e8aa0c9ce98bc06cce9d7559f3cdc9ea5dd3cffb13c30d27c28b88fbd308a74da6db3798e5b9eff8f843747"} 08:09:56 INFO - Running post-run listener: copy_logs_to_upload_dir 08:09:56 INFO - Copying logs to upload dir... 08:09:56 INFO - mkdir: /builds/slave/test/build/upload/logs 08:09:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.287232 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-24 08:09:56.077703) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 08:09:56.083466) ========= 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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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/130e32bc7a1bfa83b62a37d29c9ea6a101369aa32739c3b92e1bf2ea060650a669c039314de113038adf78b756ed0ec6891843c6837297294e8aaa4e78d8edca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f57c42f18b407e33e1b3274d9e57b81bbe54734245d2866724b0420c2feda6e58de5bc2a814443783747a3914da5d569ec15670fa4f249007313bf4a1b0d3bd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86ee4bc3ca72538d1149a3234d5d16ee33b6b8be43f833507f37225b7646be6b2b9b266a9aefab99cfcf333d14b7ba75be2f6e8ea078dcd074e23b55b08af200", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4ab7649382b91b3c2837eb4b5137785b435aa8c2e8aa0c9ce98bc06cce9d7559f3cdc9ea5dd3cffb13c30d27c28b88fbd308a74da6db3798e5b9eff8f843747"} build_url:https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011035 build_url: 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/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/130e32bc7a1bfa83b62a37d29c9ea6a101369aa32739c3b92e1bf2ea060650a669c039314de113038adf78b756ed0ec6891843c6837297294e8aaa4e78d8edca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f57c42f18b407e33e1b3274d9e57b81bbe54734245d2866724b0420c2feda6e58de5bc2a814443783747a3914da5d569ec15670fa4f249007313bf4a1b0d3bd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86ee4bc3ca72538d1149a3234d5d16ee33b6b8be43f833507f37225b7646be6b2b9b266a9aefab99cfcf333d14b7ba75be2f6e8ea078dcd074e23b55b08af200", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4ab7649382b91b3c2837eb4b5137785b435aa8c2e8aa0c9ce98bc06cce9d7559f3cdc9ea5dd3cffb13c30d27c28b88fbd308a74da6db3798e5b9eff8f843747"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 08:09:56.120220) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:09:56.120915) ========= 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.LhuLIRS8x9/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.BWnrO8YoEb/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.005126 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:09:56.173685) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 08:09:56.174199) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 08:09:56.174690) ========= ========= Total master_lag: 0.20 =========