builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0074 starttime: 1495009731.5 results: success (0) buildid: 20170516234320 builduid: c6668a1d7c4b44188cde41216a8574d3 revision: 7067d6a7dc74d854498ff5f45a45b74431e21160 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:51.501429) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:51.502157) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:51.502659) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:51.530437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:51.530845) ========= 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.QlUFACrmcn/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.4AvNYljwhd/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-17 01:28:51-- 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% 159M=0s 2017-05-17 01:28:51 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104714 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:51.658463) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:51.658772) ========= 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.QlUFACrmcn/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.4AvNYljwhd/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.028549 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:51.707952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 01:28:51.708296) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7067d6a7dc74d854498ff5f45a45b74431e21160 --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 7067d6a7dc74d854498ff5f45a45b74431e21160 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QlUFACrmcn/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.4AvNYljwhd/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-17 01:28:51,760 truncating revision to first 12 chars 2017-05-17 01:28:51,760 Setting DEBUG logging. 2017-05-17 01:28:51,760 attempt 1/10 2017-05-17 01:28:51,760 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7067d6a7dc74?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-17 01:28:52,830 unpacking tar archive at: mozilla-beta-7067d6a7dc74/testing/mozharness/ program finished with exit code 0 elapsedTime=1.340360 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 01:28:53.076250) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:53.076618) ========= script_repo_revision: 7067d6a7dc74d854498ff5f45a45b74431e21160 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:53.077082) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:53.077437) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 01:28:53.099315) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-05-17 01:28:53.099641) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.QlUFACrmcn/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.4AvNYljwhd/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:28:53 INFO - MultiFileLogger online at 20170517 01:28:53 in /builds/slave/test 01:28:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:28:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:28:53 INFO - {'append_to_log': False, 01:28:53 INFO - 'base_work_dir': '/builds/slave/test', 01:28:53 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:28:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:28:53 INFO - 'branch': 'Mozilla-Beta', 01:28:53 INFO - 'buildbot_json_path': 'buildprops.json', 01:28:53 INFO - 'config_files': ('talos/mac_config.py',), 01:28:53 INFO - 'default_actions': ('clobber', 01:28:53 INFO - 'read-buildbot-config', 01:28:53 INFO - 'download-and-extract', 01:28:53 INFO - 'populate-webroot', 01:28:53 INFO - 'create-virtualenv', 01:28:53 INFO - 'install', 01:28:53 INFO - 'run-tests'), 01:28:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:28:53 INFO - 'download_minidump_stackwalk': True, 01:28:53 INFO - 'download_symbols': 'ondemand', 01:28:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:28:53 INFO - 'tooltool.py': '/tools/tooltool.py', 01:28:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:28:53 INFO - '/tools/misc-python/virtualenv.py')}, 01:28:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:28:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:28:53 INFO - 'gecko_profile': False, 01:28:53 INFO - 'gecko_profile_interval': 0, 01:28:53 INFO - 'installer_path': 'installer.exe', 01:28:53 INFO - 'log_level': 'info', 01:28:53 INFO - 'log_name': 'talos', 01:28:53 INFO - 'log_to_console': True, 01:28:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:28:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:28:53 INFO - 'opt_config_files': (), 01:28:53 INFO - 'pip_index': False, 01:28:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:28:53 INFO - 'cmd': ('bash', 01:28:53 INFO - '-c', 01:28:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:28:53 INFO - 'enabled': True, 01:28:53 INFO - 'halt_on_failure': False, 01:28:53 INFO - 'name': 'check_screen_resolution'},), 01:28:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:28:53 INFO - 'cmd': ('bash', 01:28:53 INFO - '-c', 01:28:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:28:53 INFO - 'enabled': True, 01:28:53 INFO - 'halt_on_failure': False, 01:28:53 INFO - 'name': 'check_screen_resolution'},), 01:28:53 INFO - 'run_cmd_checks_enabled': True, 01:28:53 INFO - 'suite': 'dromaeojs-e10s', 01:28:53 INFO - 'system_bits': '32', 01:28:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:28:53 INFO - 'title': 't-yosemite-r7-0074', 01:28:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:28:53 INFO - 'use_talos_json': True, 01:28:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:28:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:28:53 INFO - 'work_dir': 'build'} 01:28:53 INFO - [mozharness: 2017-05-17 08:28:53.269315Z] Running clobber step. 01:28:53 INFO - Running pre-action listener: _resource_record_pre_action 01:28:53 INFO - Running main action method: clobber 01:28:53 INFO - rmtree: /builds/slave/test/build 01:28:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:28:55 INFO - Running post-action listener: _resource_record_post_action 01:28:55 INFO - [mozharness: 2017-05-17 08:28:55.717654Z] Finished clobber step (success) 01:28:55 INFO - [mozharness: 2017-05-17 08:28:55.717780Z] Running read-buildbot-config step. 01:28:55 INFO - Running pre-action listener: _resource_record_pre_action 01:28:55 INFO - Running main action method: read_buildbot_config 01:28:55 INFO - Using buildbot properties: 01:28:55 INFO - { 01:28:55 INFO - "project": "", 01:28:55 INFO - "product": "firefox", 01:28:55 INFO - "script_repo_revision": "production", 01:28:55 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 01:28:55 INFO - "repository": "", 01:28:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 01:28:55 INFO - "buildid": "20170516234320", 01:28:55 INFO - "pgo_build": "False", 01:28:55 INFO - "basedir": "/builds/slave/test", 01:28:55 INFO - "buildnumber": 3, 01:28:55 INFO - "slavename": "t-yosemite-r7-0074", 01:28:55 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 01:28:55 INFO - "platform": "yosemite_r7", 01:28:55 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 01:28:55 INFO - "branch": "mozilla-beta", 01:28:55 INFO - "repo_path": "releases/mozilla-beta", 01:28:55 INFO - "revision": "7067d6a7dc74d854498ff5f45a45b74431e21160", 01:28:55 INFO - "stage_platform": "macosx64", 01:28:55 INFO - "builduid": "c6668a1d7c4b44188cde41216a8574d3", 01:28:55 INFO - "slavebuilddir": "test" 01:28:55 INFO - } 01:28:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 01:28:55 INFO - Running post-action listener: _resource_record_post_action 01:28:55 INFO - [mozharness: 2017-05-17 08:28:55.721073Z] Finished read-buildbot-config step (success) 01:28:55 INFO - [mozharness: 2017-05-17 08:28:55.721191Z] Running download-and-extract step. 01:28:55 INFO - Running pre-action listener: _resource_record_pre_action 01:28:55 INFO - Running main action method: download_and_extract 01:28:55 INFO - mkdir: /builds/slave/test/build/tests 01:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:28:55 INFO - trying https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 01:28:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/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 01:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/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 01:28:56 INFO - Downloaded 1287 bytes. 01:28:56 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 01:28:56 INFO - Using the following test package requirements: 01:28:56 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:28:56 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 01:28:56 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 01:28:56 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:28:56 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 01:28:56 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:28:56 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 01:28:56 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:28:56 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:28:56 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 01:28:56 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 01:28:56 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:28:56 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 01:28:56 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:28:56 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 01:28:56 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:28:56 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 01:28:56 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:28:56 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 01:28:56 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 01:28:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 01:28:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 01:28:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 01:28:58 INFO - Expected file size: 51225060 01:28:58 INFO - Obtained file size: 51225060 01:29:14 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 01:29:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 01:29:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 01:29:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 01:29:15 INFO - Expected file size: 51225060 01:29:15 INFO - Obtained file size: 51225060 01:29:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 01:29:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 01:29:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 01:29:31 INFO - Expected file size: 14404449 01:29:31 INFO - Obtained file size: 14404449 01:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:31 INFO - trying https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:29:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 01:29:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/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 01:29:33 INFO - Downloaded 59542602 bytes. 01:29:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:29:33 INFO - mkdir: /builds/slave/test/properties 01:29:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:29:33 INFO - Writing to file /builds/slave/test/properties/build_url 01:29:33 INFO - Contents: 01:29:33 INFO - build_url:https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:29:34 INFO - Running post-action listener: _resource_record_post_action 01:29:34 INFO - Running post-action listener: set_extra_try_arguments 01:29:34 INFO - [mozharness: 2017-05-17 08:29:34.369823Z] Finished download-and-extract step (success) 01:29:34 INFO - [mozharness: 2017-05-17 08:29:34.369960Z] Running populate-webroot step. 01:29:34 INFO - Running pre-action listener: _resource_record_pre_action 01:29:34 INFO - Running main action method: populate_webroot 01:29:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:29:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:29:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:29:34 INFO - '--tppagecycles', 01:29:34 INFO - '7'], 01:29:34 INFO - 'tests': ['tsvgm']}}, 01:29:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:29:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:29:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:29:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:29:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:29:34 INFO - 'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:29:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:29:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:29:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:29:34 INFO - 'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['damp', 'tps']}, 01:29:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:29:34 INFO - 'tests': ['damp', 'tps']}, 01:29:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['dromaeo_dom']}, 01:29:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:29:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['basic_compositor_video']}, 01:29:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:29:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other-e10s': {'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['tsvgx', 01:29:34 INFO - 'tsvgr_opacity', 01:29:34 INFO - 'tart', 01:29:34 INFO - 'tscrollx', 01:29:34 INFO - 'cart', 01:29:34 INFO - 'tsvg_static']}, 01:29:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:29:34 INFO - 'tsvgr_opacity', 01:29:34 INFO - 'tart', 01:29:34 INFO - 'tscrollx', 01:29:34 INFO - 'cart', 01:29:34 INFO - 'tsvg_static']}, 01:29:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:29:34 INFO - 'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['tp5o']}, 01:29:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:29:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:29:34 INFO - 'talos_options': ['--disable-e10s', 01:29:34 INFO - '--xperf_path', 01:29:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:29:34 INFO - 'tests': ['tp5n']}, 01:29:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:29:34 INFO - 'talos_options': ['--xperf_path', 01:29:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:29:34 INFO - 'tests': ['tp5n']}}, 01:29:34 INFO - 'talos.zip': {'path': '', 01:29:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:29:34 INFO - Running post-action listener: _resource_record_post_action 01:29:34 INFO - [mozharness: 2017-05-17 08:29:34.388764Z] Finished populate-webroot step (success) 01:29:34 INFO - [mozharness: 2017-05-17 08:29:34.388898Z] Running create-virtualenv step. 01:29:34 INFO - Running pre-action listener: _resource_record_pre_action 01:29:34 INFO - Running main action method: create_virtualenv 01:29:34 INFO - Creating virtualenv /builds/slave/test/build/venv 01:29:34 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 01:29:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:29:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:29:34 INFO - Using real prefix '/tools/python27' 01:29:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:29:35 INFO - Installing distribute.............................................................................................................................................................................................done. 01:29:38 INFO - Installing pip.................done. 01:29:38 INFO - Return code: 0 01:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:29:38 INFO - Reading from file tmpfile_stdout 01:29:38 INFO - Output received: 01:29:38 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) 01:29:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:29:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:38 INFO - 'HOME': '/Users/cltbld', 01:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:38 INFO - 'LOGNAME': 'cltbld', 01:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:38 INFO - 'MOZ_NO_REMOTE': '1', 01:29:38 INFO - 'NO_EM_RESTART': '1', 01:29:38 INFO - 'PAGER': '/bin/cat', 01:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:38 INFO - 'PWD': '/builds/slave/test', 01:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:38 INFO - 'SHELL': '/bin/bash', 01:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:29:38 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:38 INFO - 'USER': 'cltbld', 01:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:38 INFO - 'XPC_FLAGS': '0x0', 01:29:38 INFO - 'XPC_SERVICE_NAME': '0', 01:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:38 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 01:29:38 INFO - Cleaning up... 01:29:38 INFO - Return code: 0 01:29:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:29:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:38 INFO - 'HOME': '/Users/cltbld', 01:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:38 INFO - 'LOGNAME': 'cltbld', 01:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:38 INFO - 'MOZ_NO_REMOTE': '1', 01:29:38 INFO - 'NO_EM_RESTART': '1', 01:29:38 INFO - 'PAGER': '/bin/cat', 01:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:38 INFO - 'PWD': '/builds/slave/test', 01:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:38 INFO - 'SHELL': '/bin/bash', 01:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:29:38 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:38 INFO - 'USER': 'cltbld', 01:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:38 INFO - 'XPC_FLAGS': '0x0', 01:29:38 INFO - 'XPC_SERVICE_NAME': '0', 01:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:38 INFO - Downloading/unpacking psutil>=3.1.1 01:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:29:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:29:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:29:40 INFO - Installing collected packages: psutil 01:29:40 INFO - Running setup.py install for psutil 01:29:40 INFO - building 'psutil._psutil_osx' extension 01:29:40 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 01:29:41 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 01:29:41 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 01:29:41 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 01:29:41 INFO - building 'psutil._psutil_posix' extension 01:29:41 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 01:29:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:29:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:29:41 INFO - ^ 01:29:41 INFO - 1 warning generated. 01:29:41 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 01:29:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:29:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:29:42 INFO - Successfully installed psutil 01:29:42 INFO - Cleaning up... 01:29:42 INFO - Return code: 0 01:29:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:29:42 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')]} 01:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:42 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')]} 01:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:42 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 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:29:42 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 01:29:42 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 01:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:42 INFO - 'HOME': '/Users/cltbld', 01:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:42 INFO - 'LOGNAME': 'cltbld', 01:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:42 INFO - 'MOZ_NO_REMOTE': '1', 01:29:42 INFO - 'NO_EM_RESTART': '1', 01:29:42 INFO - 'PAGER': '/bin/cat', 01:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:42 INFO - 'PWD': '/builds/slave/test', 01:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:42 INFO - 'SHELL': '/bin/bash', 01:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:29:42 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:42 INFO - 'USER': 'cltbld', 01:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:42 INFO - 'XPC_FLAGS': '0x0', 01:29:42 INFO - 'XPC_SERVICE_NAME': '0', 01:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:29:42 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 01:29:42 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 01:29:42 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 01:29:42 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 01:29:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:29:43 INFO - Installing collected packages: mozsystemmonitor 01:29:43 INFO - Running setup.py install for mozsystemmonitor 01:29:43 INFO - Successfully installed mozsystemmonitor 01:29:43 INFO - Cleaning up... 01:29:44 INFO - Return code: 0 01:29:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:44 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 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:29:44 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 01:29:44 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 01:29:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:29:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:44 INFO - 'HOME': '/Users/cltbld', 01:29:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:44 INFO - 'LOGNAME': 'cltbld', 01:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:44 INFO - 'MOZ_NO_REMOTE': '1', 01:29:44 INFO - 'NO_EM_RESTART': '1', 01:29:44 INFO - 'PAGER': '/bin/cat', 01:29:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:44 INFO - 'PWD': '/builds/slave/test', 01:29:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:44 INFO - 'SHELL': '/bin/bash', 01:29:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:29:44 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:29:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:44 INFO - 'USER': 'cltbld', 01:29:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:44 INFO - 'XPC_FLAGS': '0x0', 01:29:44 INFO - 'XPC_SERVICE_NAME': '0', 01:29:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:44 INFO - Downloading/unpacking jsonschema==2.5.1 01:29:44 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 01:29:44 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 01:29:44 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 01:29:44 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 01:29:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:29:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:29:45 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 01:29:45 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 01:29:45 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 01:29:45 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 01:29:45 INFO - Downloading functools32-3.2.3-2.tar.gz 01:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:29:45 INFO - warning: no files found matching '*.txt' 01:29:45 INFO - no previously-included directories found matching 'build' 01:29:45 INFO - no previously-included directories found matching 'dist' 01:29:45 INFO - no previously-included directories found matching '.git*' 01:29:45 INFO - Installing collected packages: jsonschema, functools32 01:29:46 INFO - Running setup.py install for functools32 01:29:46 INFO - warning: no files found matching '*.txt' 01:29:46 INFO - no previously-included directories found matching 'build' 01:29:46 INFO - no previously-included directories found matching 'dist' 01:29:46 INFO - no previously-included directories found matching '.git*' 01:29:46 INFO - Successfully installed jsonschema functools32 01:29:46 INFO - Cleaning up... 01:29:46 INFO - Return code: 0 01:29:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:29:46 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')]} 01:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:46 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')]} 01:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:46 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 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:29:46 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 01:29:46 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 01:29:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:29:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:46 INFO - 'HOME': '/Users/cltbld', 01:29:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:46 INFO - 'LOGNAME': 'cltbld', 01:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:46 INFO - 'MOZ_NO_REMOTE': '1', 01:29:46 INFO - 'NO_EM_RESTART': '1', 01:29:46 INFO - 'PAGER': '/bin/cat', 01:29:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:46 INFO - 'PWD': '/builds/slave/test', 01:29:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:46 INFO - 'SHELL': '/bin/bash', 01:29:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:29:46 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:29:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:46 INFO - 'USER': 'cltbld', 01:29:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:46 INFO - 'XPC_FLAGS': '0x0', 01:29:46 INFO - 'XPC_SERVICE_NAME': '0', 01:29:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:29:46 INFO - Cleaning up... 01:29:46 INFO - Return code: 0 01:29:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:29:46 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')]} 01:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:46 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')]} 01:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:46 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 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:29:46 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 01:29:46 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 01:29:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:29:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:46 INFO - 'HOME': '/Users/cltbld', 01:29:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:46 INFO - 'LOGNAME': 'cltbld', 01:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:46 INFO - 'MOZ_NO_REMOTE': '1', 01:29:46 INFO - 'NO_EM_RESTART': '1', 01:29:46 INFO - 'PAGER': '/bin/cat', 01:29:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:46 INFO - 'PWD': '/builds/slave/test', 01:29:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:46 INFO - 'SHELL': '/bin/bash', 01:29:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:29:46 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:29:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:46 INFO - 'USER': 'cltbld', 01:29:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:46 INFO - 'XPC_FLAGS': '0x0', 01:29:46 INFO - 'XPC_SERVICE_NAME': '0', 01:29:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:46 INFO - Downloading/unpacking blobuploader==1.2.4 01:29:46 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 01:29:46 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 01:29:46 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 01:29:46 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 01:29:48 INFO - Downloading blobuploader-1.2.4.tar.gz 01:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:29:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:29:48 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 01:29:48 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 01:29:48 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 01:29:48 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 01:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:29:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:29:48 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 01:29:48 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 01:29:48 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 01:29:48 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 01:29:48 INFO - Downloading docopt-0.6.1.tar.gz 01:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:29:49 INFO - Installing collected packages: blobuploader, requests, docopt 01:29:49 INFO - Running setup.py install for blobuploader 01:29:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:29:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:29:49 INFO - Running setup.py install for requests 01:29:49 INFO - Running setup.py install for docopt 01:29:49 INFO - Successfully installed blobuploader requests docopt 01:29:49 INFO - Cleaning up... 01:29:49 INFO - Return code: 0 01:29:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:29: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')]} 01:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:50 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')]} 01:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:50 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 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:29:50 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 01:29:50 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 01:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:50 INFO - 'HOME': '/Users/cltbld', 01:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:50 INFO - 'LOGNAME': 'cltbld', 01:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:50 INFO - 'MOZ_NO_REMOTE': '1', 01:29:50 INFO - 'NO_EM_RESTART': '1', 01:29:50 INFO - 'PAGER': '/bin/cat', 01:29:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:50 INFO - 'PWD': '/builds/slave/test', 01:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:50 INFO - 'SHELL': '/bin/bash', 01:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:29:50 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:50 INFO - 'USER': 'cltbld', 01:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:50 INFO - 'XPC_FLAGS': '0x0', 01:29:50 INFO - 'XPC_SERVICE_NAME': '0', 01:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:29:50 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-UDEtgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-FvHt8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-YCKpYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-0DjnAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-oDQ_uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-RzjQlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-j9GP9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-4igSjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-PqywCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:29:51 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-uLhHCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:29:52 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-aZaeMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:29:52 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-6urBdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:29:52 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-61_xx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:29:52 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-OD9P7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:29:52 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-1HP6q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:29:52 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-5A2z7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:29:52 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-AT2RGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:29:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:29:52 INFO - Running setup.py install for manifestparser 01:29:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:29:52 INFO - Running setup.py install for mozcrash 01:29:53 INFO - Running setup.py install for mozdebug 01:29:53 INFO - Running setup.py install for mozdevice 01:29:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Running setup.py install for mozfile 01:29:53 INFO - Running setup.py install for mozhttpd 01:29:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Running setup.py install for mozinfo 01:29:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Running setup.py install for mozInstall 01:29:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Running setup.py install for mozleak 01:29:54 INFO - Running setup.py install for mozlog 01:29:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for moznetwork 01:29:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for mozprocess 01:29:54 INFO - Running setup.py install for mozprofile 01:29:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for mozrunner 01:29:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for mozscreenshot 01:29:55 INFO - Running setup.py install for moztest 01:29:55 INFO - Running setup.py install for mozversion 01:29:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:29:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:29:55 INFO - Cleaning up... 01:29:55 INFO - Return code: 0 01:29:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:55 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 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:29:55 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 01:29:55 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 01:29:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:29:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:55 INFO - 'HOME': '/Users/cltbld', 01:29:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:55 INFO - 'LOGNAME': 'cltbld', 01:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:55 INFO - 'MOZ_NO_REMOTE': '1', 01:29:55 INFO - 'NO_EM_RESTART': '1', 01:29:55 INFO - 'PAGER': '/bin/cat', 01:29:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:55 INFO - 'PWD': '/builds/slave/test', 01:29:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:55 INFO - 'SHELL': '/bin/bash', 01:29:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:29:55 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:29:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:55 INFO - 'USER': 'cltbld', 01:29:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:55 INFO - 'XPC_FLAGS': '0x0', 01:29:55 INFO - 'XPC_SERVICE_NAME': '0', 01:29:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-qCmgoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BQky9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ItrM8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-zqBc7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-_HmE6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-m9Hz_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-FDVnKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-RdBRLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-l5XrxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vo1DRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-UOt_0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ePqc7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-dRdodh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-jZrpO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-0j1AGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-arufk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-RcuOv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:29:57 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)) 01:29:57 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)) 01:29:57 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)) 01:29:57 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)) 01:29:57 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)) 01:29:57 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)) 01:29:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:29:59 INFO - Downloading blessings-1.6.tar.gz 01:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:29:59 INFO - Installing collected packages: blessings 01:29:59 INFO - Running setup.py install for blessings 01:29:59 INFO - Successfully installed blessings 01:29:59 INFO - Cleaning up... 01:29:59 INFO - Return code: 0 01:29:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:29:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:30:00 INFO - Reading from file tmpfile_stdout 01:30:00 INFO - Current package versions: 01:30:00 INFO - blessings == 1.6 01:30:00 INFO - blobuploader == 1.2.4 01:30:00 INFO - docopt == 0.6.1 01:30:00 INFO - functools32 == 3.2.3-2 01:30:00 INFO - jsonschema == 2.5.1 01:30:00 INFO - manifestparser == 1.1 01:30:00 INFO - mozInstall == 1.12 01:30:00 INFO - mozcrash == 1.0 01:30:00 INFO - mozdebug == 0.1 01:30:00 INFO - mozdevice == 0.49 01:30:00 INFO - mozfile == 1.2 01:30:00 INFO - mozhttpd == 0.7 01:30:00 INFO - mozinfo == 0.9 01:30:00 INFO - mozleak == 0.1 01:30:00 INFO - mozlog == 3.4 01:30:00 INFO - moznetwork == 0.27 01:30:00 INFO - mozprocess == 0.25 01:30:00 INFO - mozprofile == 0.28 01:30:00 INFO - mozrunner == 6.13 01:30:00 INFO - mozscreenshot == 0.1 01:30:00 INFO - mozsystemmonitor == 0.3 01:30:00 INFO - moztest == 0.8 01:30:00 INFO - mozversion == 1.4 01:30:00 INFO - psutil == 3.1.1 01:30:00 INFO - requests == 1.2.3 01:30:00 INFO - wsgiref == 0.1.2 01:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:00 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 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:30:00 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 01:30:00 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 01:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:00 INFO - 'HOME': '/Users/cltbld', 01:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:00 INFO - 'LOGNAME': 'cltbld', 01:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:00 INFO - 'MOZ_NO_REMOTE': '1', 01:30:00 INFO - 'NO_EM_RESTART': '1', 01:30:00 INFO - 'PAGER': '/bin/cat', 01:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:00 INFO - 'PWD': '/builds/slave/test', 01:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:00 INFO - 'SHELL': '/bin/bash', 01:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:30:00 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:00 INFO - 'USER': 'cltbld', 01:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:00 INFO - 'XPC_FLAGS': '0x0', 01:30:00 INFO - 'XPC_SERVICE_NAME': '0', 01:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 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)) 01:30:00 INFO - Cleaning up... 01:30:00 INFO - Return code: 0 01:30:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a507b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a50a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef90cce0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, '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.QlUFACrmcn/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.4AvNYljwhd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 01:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:00 INFO - 'HOME': '/Users/cltbld', 01:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:00 INFO - 'LOGNAME': 'cltbld', 01:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:00 INFO - 'MOZ_NO_REMOTE': '1', 01:30:00 INFO - 'NO_EM_RESTART': '1', 01:30:00 INFO - 'PAGER': '/bin/cat', 01:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:00 INFO - 'PWD': '/builds/slave/test', 01:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:00 INFO - 'SHELL': '/bin/bash', 01:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:30:00 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:00 INFO - 'USER': 'cltbld', 01:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:00 INFO - 'XPC_FLAGS': '0x0', 01:30:00 INFO - 'XPC_SERVICE_NAME': '0', 01:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:30:00 INFO - Cleaning up... 01:30:00 INFO - Return code: 0 01:30:00 INFO - Running post-action listener: _resource_record_post_action 01:30:00 INFO - Running post-action listener: _start_resource_monitoring 01:30:00 INFO - Starting resource monitoring. 01:30:00 INFO - [mozharness: 2017-05-17 08:30:00.783637Z] Finished create-virtualenv step (success) 01:30:00 INFO - [mozharness: 2017-05-17 08:30:00.784580Z] Running install step. 01:30:00 INFO - Running pre-action listener: _resource_record_pre_action 01:30:00 INFO - Running main action method: install 01:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:30:01 INFO - Reading from file tmpfile_stdout 01:30:01 INFO - Detecting whether we're running mozinstall >=1.0... 01:30:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:30:01 INFO - Reading from file tmpfile_stdout 01:30:01 INFO - Output received: 01:30:01 INFO - Usage: mozinstall [options] installer 01:30:01 INFO - Options: 01:30:01 INFO - -h, --help show this help message and exit 01:30:01 INFO - -d DEST, --destination=DEST 01:30:01 INFO - Directory to install application into. [default: 01:30:01 INFO - "/builds/slave/test"] 01:30:01 INFO - --app=APP Application being installed. [default: firefox] 01:30:01 INFO - mkdir: /builds/slave/test/build/application 01:30:01 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'] 01:30:01 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 01:30:16 INFO - Reading from file tmpfile_stdout 01:30:16 INFO - Output received: 01:30:16 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 01:30:16 INFO - Running post-action listener: _resource_record_post_action 01:30:16 INFO - [mozharness: 2017-05-17 08:30:16.140496Z] Finished install step (success) 01:30:16 INFO - [mozharness: 2017-05-17 08:30:16.140639Z] Running run-tests step. 01:30:16 INFO - Running pre-action listener: _resource_record_pre_action 01:30:16 INFO - Running main action method: run_tests 01:30:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:30:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:30:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:30:16 INFO - 2017-05-17 01:30:16.155 screenresolution[1618:7434] starting screenresolution argv=screenresolution get 01:30:16 INFO - 2017-05-17 01:30:16.157 screenresolution[1618:7434] Display 0: 1600x1200x32@60 01:30:16 INFO - 2017-05-17 01:30:16.163 screenresolution[1619:7436] starting screenresolution argv=screenresolution list 01:30:16 INFO - Available Modes on Display 0 01:30:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:30:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:30:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:30:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:30:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:30:16 INFO - Intel Iris: 01:30:16 INFO - Chipset Model: Intel Iris 01:30:16 INFO - Type: GPU 01:30:16 INFO - Bus: Built-In 01:30:16 INFO - VRAM (Dynamic, Max): 1536 MB 01:30:16 INFO - Vendor: Intel (0x8086) 01:30:16 INFO - Device ID: 0x0a2e 01:30:16 INFO - Revision ID: 0x0009 01:30:16 INFO - Displays: 01:30:16 INFO - Display: 01:30:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:30:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:30:16 INFO - Display Serial Number: 1600x1200 60 01:30:16 INFO - Main Display: Yes 01:30:16 INFO - Mirror: Off 01:30:16 INFO - Online: Yes 01:30:16 INFO - Rotation: Supported 01:30:16 INFO - Return code: 0 01:30:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:30:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:30:16 INFO - Python 2.7.3 01:30:16 INFO - Return code: 0 01:30:16 INFO - grabbing minidump binary from tooltool 01:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:16 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 0x10a2e5d30>, '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 0x10a20cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2dabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:30:16 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 01:30:16 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 01:30:16 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 01:30:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:30:16 INFO - Return code: 0 01:30:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:30:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:30:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:30:16 INFO - ENV: RUST_BACKTRACE is now 1 01:30:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:30:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:30:16 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-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:30:16 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-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:30:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QlUFACrmcn/Render', 01:30:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:16 INFO - 'HOME': '/Users/cltbld', 01:30:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:16 INFO - 'LOGNAME': 'cltbld', 01:30:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:30:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:16 INFO - 'MOZ_NO_REMOTE': '1', 01:30:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:30:16 INFO - 'NO_EM_RESTART': '1', 01:30:16 INFO - 'PAGER': '/bin/cat', 01:30:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:16 INFO - 'PWD': '/builds/slave/test', 01:30:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:30:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:16 INFO - 'RUST_BACKTRACE': '1', 01:30:16 INFO - 'SHELL': '/bin/bash', 01:30:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4AvNYljwhd/Listeners', 01:30:16 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 01:30:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:16 INFO - 'USER': 'cltbld', 01:30:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:16 INFO - 'XPC_FLAGS': '0x0', 01:30:16 INFO - 'XPC_SERVICE_NAME': '0', 01:30:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:30:16 INFO - mozversion application_buildid: 20170516234320 01:30:16 INFO - mozversion application_changeset: 7067d6a7dc74d854498ff5f45a45b74431e21160 01:30:16 INFO - mozversion application_display_name: Firefox 01:30:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:30:16 INFO - mozversion application_name: Firefox 01:30:16 INFO - mozversion application_remotingname: firefox 01:30:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:30:16 INFO - mozversion application_vendor: Mozilla 01:30:16 INFO - mozversion application_version: 54.0 01:30:16 INFO - mozversion platform_buildid: 20170516234320 01:30:16 INFO - mozversion platform_changeset: 7067d6a7dc74d854498ff5f45a45b74431e21160 01:30:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:30:16 INFO - mozversion platform_version: 54.0 01:30:16 INFO - using testdate: 1495009816 01:30:16 INFO - actual date: 1495009816 01:30:16 INFO - starting webserver on 'localhost:49225' 01:30:16 INFO - SUITE-START | Running 2 tests 01:30:16 INFO - TEST-START | dromaeo_css 01:30:16 INFO - operating with platform_type : mac_ 01:30:16 INFO - Initialising browser for dromaeo_css test... 01:30:16 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpuZmo79/profile http://localhost:49225/getInfo.html) 01:30:19 INFO - PROCESS | 1629 | 2017-05-17 01:30:19.520 plugin-container[1632:7604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 01:30:19 INFO - PROCESS | 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:30:19 INFO - PROCESS | 1629 | 2017-05-17 01:30:19.521 plugin-container[1632:7604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 01:30:19 INFO - PROCESS | 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:30:19 INFO - PROCESS | 1629 | 2017-05-17 01:30:19.521 plugin-container[1632:7604] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:30:19 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 01:30:19 INFO - PROCESS | 1629 | colorDepth:24 01:30:19 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 01:30:19 INFO - PROCESS | 1629 | __metrics 01:30:19 ERROR - PROCESS | 1629 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 01:30:20 INFO - TEST-INFO | 1629: exit 0 01:30:20 INFO - Browser initialized. 01:30:20 INFO - Running cycle 1/1 for dromaeo_css test... 01:30:20 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpuZmo79/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:30:22 INFO - PROCESS | 1633 | 2017-05-17 01:30:22.670 plugin-container[1634:7740] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 01:30:22 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:30:22 INFO - PROCESS | 1633 | 2017-05-17 01:30:22.671 plugin-container[1634:7740] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8613, name = 'com.apple.CFPasteboardClient' 01:30:22 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:30:22 INFO - PROCESS | 1633 | 2017-05-17 01:30:22.671 plugin-container[1634:7740] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:30:32 INFO - PROCESS | 1633 | RSS: Main: 174084096 01:30:32 INFO - PROCESS | 1633 | 01:33:34 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 01:33:36 INFO - PROCESS | 1633 | RSS: Main: 189116416 01:33:36 INFO - PROCESS | 1633 | 01:36:39 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 01:36:39 INFO - PROCESS | 1633 | RSS: Main: 202375168 01:36:39 INFO - PROCESS | 1633 | 01:39:41 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 01:39:42 INFO - PROCESS | 1633 | RSS: Main: 205414400 01:39:42 INFO - PROCESS | 1633 | 01:42:44 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 01:42:44 INFO - PROCESS | 1633 | RSS: Main: 208572416 01:42:44 INFO - PROCESS | 1633 | 01:45:47 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 01:45:48 INFO - PROCESS | 1633 | RSS: Main: 211456000 01:45:48 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 01:48:51 INFO - PROCESS | 1633 | RSS: Main: 209674240 01:48:51 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | __start_tp_report 01:48:51 INFO - PROCESS | 1633 | _x_x_mozilla_page_load 01:48:51 INFO - PROCESS | 1633 | _x_x_mozilla_page_load_details 01:48:51 INFO - PROCESS | 1633 | |i|pagename|runs| 01:48:51 INFO - PROCESS | 1633 | |0;dojo.html;2804.3830357321385;3576.6423357664235;3604.819759012049;3611.78329300242;3637.7453578249524;5248.89502209956;5373.623846330758;6168.969155154224;6188.845278868031;6212.65830379329;6195.938040619592;6241.843953901143;6251.374862513746;6257.624542527452;6275.090111933767;5517.503424691787;5559.082751346023;5568.387477377485;5569.972150139243;5645.096784514479;4729.290606409032;5626.943730562703;5645.2096706461125;5648.096304591266;5681.602287839833;5412.079946409101;5422.15956526739;5550.833474995757;5619.072852979253;5621.353544342392;5501.614886957915;5507.339119305669;5515.7242137892945;5519.172124181365;5523.972380138073;4502.707324023928;5379.408265090837;5528.336599608073;5529.976954263434;5550.16747487876;6280.528960327993;6302.149209856657;6304.243490781089;6306.243250809932;6321.494280457552;5323.935212957385;6152.107944348045;6559.311272316386;6576.243731970837;6601.570897891622;6376.266729326094;6412.839679008015;6517.674116294166;6714.563553369016;6721.966390168018;6183.134361189437;6228.844278893019;6303.9054414183365;6323.083152942798;6343.9682801585695;6791.456683465311;6802.65986700663;6859.691313890887;6865.313468653095;6871.03805467235;6314.747410103636;6326.145970294044;6478.028295755675;6483.513736469784;6507.609543427409;6531.248906375733;6536.542442029107;6591.736330546821;6631.535792494479;6633.535652504375;6571.967140164268;6572.047053177263;6581.86836263272;6583.539152259392;6587.143671322698;6271.466925311308;6470.38531339522;6511.902321465182;6527.281998980108;6568.67156642166;5153.974230128825;5170.896582068338;5188.169892817131;5260.579153667774;5264.710440925709;4582.404287442678;4593.678442508993;4630.4443466784205;4672.08894265615;4718.339432479455;6348.047792831112;6361.109444677749;6367.172267605181;6373.203349581302;6380.904286435707;6582.506312026617;6719;6729.2934241903895;6736.05695202672;6744.865102697919;6147.293061297919;6179.5056395488255;6223.1287619733275;6300.495960323163;6350.714217860207;5906.616069955439;5917.31950825652;5917.497012753965;5918.201042859245;5943.613665111754;6866.656667166621;6883.174019117738;6894.207166175954;6907.481938854707;6921.480888933451;6373.521985850987;6461.8061458156335;6557.082008518716;6648.335166483506;6742.08981787472;4949.480304567968;6521.771737989147;6827.658617069226;6875.106236189264;6875.7937261882225;6499.6750162492635;6583.111279977142;6584.308647592125;6589.24223714279;6590.176227971504;6391.6804159792755;6414.294427612866;6551.148350714377;6668.933310666831;6683.331666833278;6478.449331806661;6524.771632992823;6526.184226971629;6526.902096468461;6541.476681865344;5838.883222335615;6208.9689551551955;6489.059086432535;6497.415232629086;6515.315891831289;4818.494058123846;6234.220722409699;6236.781712640036;6332.271788744353;6393;6423.42189202974;6427.871442571029;6448.613083215022;6496.9025464617125;6541.803745887631;6054.698239878448;6372.0760489729655;6383;6389.4888408926245;6412.294647588668 01:48:51 INFO - PROCESS | 1633 | |1;ext.html;738.3613174603967;863.1325517854557;900.2213085680888;920.3097676742439;969.2827307792236;32178.517322240157;39259.99999999996;39300.803495982516;40602;40639.79680101595;38797.22405551887;38966.61033389665;39148;39248.01879953003;39262.82211533659;7842.960785196066;7970.840583188362;8082.67669293229;8222.753317400488;8225.79435514111;11593.246438981481;11852;11858.703532411673;11865.881341186607;11881.108916831228;11874.22817516863;11930.522779088826;11941.701457463547;11966.982806461461;11975.221610595327;11778.705532361673;11796.174267801258;11822.349770762605;11831.349275789828;11838;11529.538818447252;11544.653660390202;11562.653120406401;11569.595064172714;11586.710332241677;39712;39768.40347394792;39813;39837.80081099605;39871.60128398708;4003.1793482336343;4019.622417125036;4037.6769858411258;4097.016715988141;4109.794510274504;27865.86067069652;28406.147815565568;28467.857660711772;28531.714682853322;28993.42013159746;3219.0825614699697;3239.5950506186728;3314.2045908981913;3362.29391827714;3421.606515250753;348491;349338.5066149357;349400.99999999744;349656.2517187423;350461;44719.776401117786;45284;53555;53576.73211633995;53666.92666146733;48189.5181048192;54655.453445465035;54993.72503137459;55281.44718552763;55343.72328138414;13758.862411375758;15147.394104235707;15255.08469491834;15562.144082075369;15641.921790390976;13233.470661173445;13292.401841917137;13314.068015239034;13321.934245260356;13376.130551514121;10761.246712730033;12364.381780911099;12383.318917459626;12396.442160102817;12529.812052819216;5177.223416487482;5555.388907220203;5813.593048486566;5837.066069428871;5838;819.8934138562068;878.1876763993281;903.7650210945202;911.7401540560908;923.9538023098953;22088.558228835333;27813.443731125266;28365.858170709016;29185.56221656677;29877;964.9228061755043;1100.6367898593444;1119.1031202112504;1138.698244965085;1144.7710457908452;10315;12245.142840001292;12505.249685018927;14090.71818563623;14343.282835858165;502580;504252;504932;506505;507289;47409;47547.28679069817;47595.76202118967;47626.76186619114;47802.76098619554;1182.822576613515;1494.0512774388342;1496.835348111685;1502.1512845242403;1503.3535579700833;26278.21165365042;26480.602790957764;26509.33726656849;26555.601665975413;26918.461630767666;38137.427938581495;38236.808815955745;38283.61716382801;38367;38387.84836454911;439311;439967;442067;442492;442506;29365.265868352606;29520.557191641714;29770.40459190761;30174.396512069197;31863.84068079645;39745.80127099346;39824;39993;40752.59247407607;42020;23730.406739831644;26696.19911402661;28913.277168070126;29472.263193420335;29836.850815745784;28773;28965.85517072401;29384.706152938197;29448.11655650334;29731.256718582208;33757.49363759496;34151.658483414845;34354.48468272928;34646.65353346535;34709.305813884086;29121.7087829119;29171.562426563192;29407;29968.550471743376;30134.69865301319;22671.319860404215;22962.885185573967;23720.288391348287;24618.384540385916;24978 01:48:51 INFO - PROCESS | 1633 | |2;jquery.html;423.83894120234305;429.31309904153346;434.4287213281947;436.1364498293378;447.0656328273909;21871.343859684195;22150.66773998389;22638;22841.200557980475;23042.193523226648;23040.769592304074;23627.40931476714;23932.282031539038;24021.519569608598;24265.636015459782;6763.830904227384;6845.6919438625255;6962.477814163958;7049.295070492961;7126.536775109627;6926.341997510229;6964.930350696479;7025.929740702604;7690.3078722914925;8065.2741253287195;8049.637766300501;8149.633266502992;8163.6734530618705;8173.5504547249875;8174.6730130794995;7597.354224890863;7868.52788832669;7869.8032549186155;7877.60611969405;7883.763487095368;7330.193678695339;7800.687972481094;7874.251946065115;7874.685012599489;7890.38247159331;25822.354441138934;27088.458230835273;27129;27335.726642733516;27831;2568.5119827232766;2911.878926613248;2930.1795497260587;2975.5685425613383;3025.3041800385813;13943.790843137362;15577.688446231012;16228.026318420933;16990.915045424816;17209.397671081577;1740.9390671326569;2160.2979031814566;2165.945851353713;2167.642339014061;2181.083944743196;156714;159733;165568;165689;169158;20708.378748637566;22077.448063798533;22272.88863555672;22425.10299587998;22492.437689057904;32513;32758.181045474055;33020.339593208;33238.3352332952;33328.66671333255;10559.366438013743;10847.51186196623;10937.343759374464;10940.179486538382;11130.276532025393;8316;9097.40866843653;9126.178643921947;9297.023812499725;9325.114114159143;8316.41785075039;9663.855042174373;9881.75295617601;10000.449975251431;10136.391816491034;4395.978020109943;4455.440595791447;4478.641708663299;4522.540256703624;4525.411696479437;904.3036861616519;914.1133100892124;927.7634203278255;939.6617217801586;957.6169532187181;21488.355349339545;22057.33827985163;22140;22155.224567140074;22206.222782202545;848.7793173774863;955.8375076237056;962.1629182611033;988.4365911430416;993.1558175550724;11277.323360598226;11379;11391.088712902947;11394.031507321804;11450.885491145149;659769;683176;683537;737187;746081;133849;158327;164154;171281;172652;1246.7007918099773;1328.35574746248;1342.8992825538123;1344.1934839096464;1350.6015725361042;19926.20295188176;20256.493587660425;20651.27720529774;20719;20766.273180438613;64178;64918.675406624556;65443.67278163579;65736.0139597916;69916;96193;96636;96772;96926;98511.52232716659;9058.682946096855;11555.595554155565;11588.304701717923;11604.361760103275;11900.04799616046;13245.20528768277;13290.867091328962;13294.40175192138;13705.931470342983;13782.65543361424;9853.852192216978;11360.318380897173;11718.648440546796;11800.291982481078;11896.702582435508;8014.719484818003;8835.425697329652;10836.512356944115;11077.944610276896;11183.72040698989;9895.65365212214;10885.74813896412;11670.066394688234;11868;12104.576339828063;9447.952760236154;9719.611215551298;11598.130140239684;11806.232594881305;11875.643730688093;11565.132615054074;11778.941105294418;11802;11848.99283560907;11946.163768536118 01:48:51 INFO - PROCESS | 1633 | |3;mootools.html;1626.1462732065672;1646.448439772676;1662.1938359895455;1669.824668409817;1672.9749053764197;10932.945335273313;11206.719832004204;11258.155638327118;11969.820452693217;11971.820422693656;10375.221858360615;11148.163887708413;11588.42057897106;11656.242344247617;11766.411679416038;2415.0943396226457;2450.1179575352858;2457.1891275878916;2462.3597864555168;2468.5556599812026;2399.4601214726636;2407.0691612775863;2433.1605596069326;2447.877606119696;2448.3634255093716;2379.0721618568773;2410.6986626671664;2430.723869968263;2441.084593277521;2473.51766405551;2360.8229382796358;2409.771071748181;2416.2147302126878;2421.7457166997474;2433.2700189943034;2396.760323967607;2409.217004473553;2422.467057247403;2424.527217192648;2429.3683642252963;11513.309201447857;11659.591914282959;11723;11743.94128029363;11746.823797643043;5812;5969.164316995624;5977.8804423911715;6001.009833377488;6009.128676341949;3494.877679281238;3609;3649.9635003649887;3667.7249206309366;3694.6674799268194;1244.4337826289018;1252.3738130934532;1277.2783377391745;1297.753426848905;1298.039450806406;14196;14560.563183104523;15398.46105386306;15519.224038798127;15638.843611563852;4569.588737013684;4626.352310676508;4754.95245047552;4763.642726795504;4765.499622539653;12150.270983740827;12232.143749937597;12278.754424911453;12285.324307163191;12291.385430728427;4734.905301893943;5794.594378393472;5825.067989121805;5838.7080645967535;5864.736086876015;5206.817761378409;5248.6325957182635;5274.340707411574;5282.6302158849285;5308.681479111266;3915.8629447969183;3982.78094704794;3987.501562304712;4009.1981603679383;4079.204555111784;1027.4913917610681;1031.2781053262745;1031.4275577054657;1041;1044.2794471814423;818.3044412249659;824.9092599814015;877.6884206106868;966.3042609321277;972.4700038479045;5732.856678583069;6688.498362622823;6840.076589660432;6857.074294970115;6878.621675807779;950.50098698184;1086.1582273737758;1094.6168840905777;1101.8567586213742;1110.3448965110624;4358.433403657505;4420.778961051935;4449.822007119679;4550.271956486946;4585;12568.811467827807;13753.656158595915;14385.64035899111;14432;14432.278386080654;13572.117812342167;13757.724845503033;14420.42318307277;14423;14451.92774036123;8499.702510412106;9178.908210917938;10267.383956962456;10345.793084138275;10420.895791042145;6491;6907.585544867324;7263.237360077116;7276.345128938421;7305.013823134022;9936.453495057842;12007.579734709243;12058.819117713418;12087.879121208676;12129.575464858688;13117.016223783063;13136.60590182296;13189.076764626385;13208.735825283633;13321.933390332986;9494.0980606845;9778;9782.853257201004;9787.706368808947;9794.26543009263;10656.467176641292;10684.412357320422;10794.892051079389;10840.186985975926;10867.29362591429;9109.271258299188;9201.263898888206;9205.86191207119;9302.488363140092;9396.67111651089;8785.516796575994;9031.232345250728;9039.954800225956;9065.32010099232;9104.590293436679;8002.799930001796;8124.67501299965;8127.634256458356;8131.512109273463;8140.348772098337;5963.3142188647125;5970.223870896929;6048.123022161674;6062.878742425215;6148.077788331607;4863.829765958175;5938.406159384027;6100.481459076028;6274.023682539985;6369.108324834619 01:48:51 INFO - PROCESS | 1633 | |4;prototype.html;344.8086312096786;388.45616137407626;389.61038961038963;403.334498078171;403.3747691078829;4313.762743049131;5128.512791284831;5278.5513231375335;5331.054165229692;5412.729363531817;4715.834945776898;4961.875953101175;5417.431169727181;5423.430539793324;5443.319585051869;3461.567304086989;4398.912021759563;4406.911861762763;4460.66545009124;4503.256962601167;4084.9591504084874;4454.398656181423;4459.286514157736;4483.282674772037;4508.278675411935;3733.1040550268012;4348.869533913972;4439.400680908085;4464.508904020555;4497.64018878489;3839.3856982882885;3974.662153716923;4064.7764372959477;4219.45147130871;4487.640988720895;3095.034652995364;3868.439076333944;4073.5111786585503;4086.856960006415;4098.467199264106;4241.936370954438;4385.101054283896;5190.740462976836;5271.894562108775;5286.312779338661;4638.907221855544;4644.814207431698;5221.660592061542;5226.830865190941;5236.7905283788605;5144.993452553507;5708.97145514274;5727.713614319267;5770.422957704196;5791.420857914217;3987.6809855211513;4638.327442520849;4645.349651048856;4660.906781864343;4688.906221875543;4963.528464795875;4980.9252861207115;5994.730237139339;6236.750529978795;6274.811755647338;4100.34394496879;4429.579189976947;4446.421965144576;4762.357081793983;4787.521247875185;4836.830710925101;4855.32025516428;4857.489963553776;4865.9319789089495;4925.852224433273;4895.912309651565;4995.952468993911;5186.196139598368;5199.584033277329;5220.634555581149;5735.971320143456;5740.311162660508;5745.328422283658;5746.741396637161;5832.005076522596;1411.336671764269;1529.4035326222781;1543.575516732885;1617.5147455763224;1650.6877032758985;1004.6584161385164;1168.258156070884;1170.7599942011866;1242.7514497100617;1251.3242848861512;4773.570378665867;4822.855314340575;4983.651144419856;5343.488560376617;5358.223057656618;4930.950690493121;5704.11586204139;5900.878833021712;5926.407359264125;5932.79235226765;4833.4441539222735;5237.685738855681;5270.284449463119;5319.627626066215;5428.4842939920645;2864.8854045837934;2868.584055311968;2963.748081413061;3076.8153910765427;3099.411111888734;5332.9466705333225;5463.562914966793;5860.384659610765;6076.392360763888;6094.537311045317;5040.647154699209;5855.648661080349;5907.881842363128;6001.309849367379;6066.75732970685;5059.1163037069255;6022.126791615191;6041.939580604226;6185.350538193425;6245.968770156211;5252.7898884045735;5867.970660146672;6092.664903430354;6104.481119104926;6125.632462052291;5009.41262240153;5031.144705400017;5685.886282274414;5739.282589676291;5937.376575459687;5209.541328428047;5440.646357986719;6206.503479721701;6234.688265586793;6308.432241098323;4471.485779135441;5217.278298161522;5274.657147285414;5279.445658205987;5323.733813309396;4839.830605928775;5539.695316757617;5662.2639056922335;5766.769329226951;5992.580519363602;5657.038303488499;5835.533157347487;5853.707314634336;5886.6173698709445;5889.793857214977;5225.843224703265;5452.5365343946205;5477.616566840283;5537.335519737658;5817;5007.29606012749;5128.230765385073;5421.807202415463;5527.803864367482;5671.206031155724;4968.701877887338;5399.541039011728;5640;5896.528277737685;5897.557683173865;4180.937285940652;5049.2930989660745;5410.567154627699;5597.272354593877;5679.716014199191 01:48:51 INFO - PROCESS | 1633 | |5;yui.html;746.2947514598707;803.1927912447989;806.234077626255;818.7666515043214;900.4012331799352;15899.04605723656;16760.245788939497;16917;17036.999999999985;17144.54271386932;12904.870951290484;15793.447229346975;15824.367025318974;15834;15838.366465341373;941.7080704981461;963.7976025034716;1012.3470361616753;1013.7921726046179;1014.1025192704473;1011.039512463163;1014.6093753904726;1016.6492560066802;1016.8474728790703;1020.2042406922612;1063.8191507443714;1063.9785805626607;1065.1265961911236;1067.4769363012108;1069.5080263078953;1041.1774697988617;1048.09863517375;1060.0459586372288;1060.671191930504;1060.7613287010438;773.6595897805004;785.3442375616368;786.182370334851;786.3748320085546;786.7403756760256;16063.91968040164;16552.42066527678;17184.57038574033;17531.73702394465;17554.385596504188;14539.982201245917;14635.560933172022;14768.852311476856;14904.478343257933;15037.624059398493;11881.762364752743;12194.207376520586;12269.815952760715;12692.936535317263;12792.616221513277;3713.9814300928592;3865.246276975991;3948.4077388391684;3958.2083583283456;3972.9006774830573;110044;111715.88284117136;112136;112564;112904;11383.817220393063;11500;11573.479193436315;11832.408379581158;11894.345810980307;23690.644640330407;23942.281731548082;24321;24383.634245486333;24476.51046979086;8220.671173153127;8227.095019547845;8247.257746802816;8294.83410331802;8393.202645748644;4673.299005149255;4705.788239529229;4738.573528382462;4746.905061898743;4757.317894841927;3523.119220194951;3591.479235510836;3618.457231415256;3633.327834350601;3640.12636967126;506.3822136992863;596.1385797522624;598.8682489852225;604.5133667397699;612.4793925163665;695.9681502735252;718.8418547919449;726.6003697966203;728.5519405565645;768.5043147170156;10682.73293167677;10825.566977320976;10910.618128365628;11249.43752812356;11326.716832079264;695.4714417043084;728.1953441447231;739.6782399656166;750.7897788619196;752.2477522477523;4856.587190088882;5044.167712327425;5296.761645725952;5298.3906850712665;5301.496357845998;89921.55039224762;92151.0784892156;93377;93913.5304323474;94110.0588994115;62613.68693156505;77944.61027694825;78301;87188;89971.55014225018;61497;62068;79446.20553794388;80668;81705.77441338384;9827.213822894295;11070.944645276722;11160.218784684994;11417.942910285396;11556.422178890854;50466.2430063542;51224;52197.7390113047;52735;52782.736086319324;64449.355506444335;64985.67507162434;65042.34957650363;65236.34763652303;65356.36609084811;13122.540711075448;13281;13436.395362208528;13474.663133421744;14008.299585020912;15289.77065344043;15448.922755386151;15462.994905331117;15507.689846203239;16098.436554720529;13916.721665566834;13953.232572208626;14244.786328204878;14328.355224015153;14347.210903399995;11491.885081149081;11985.041196704069;14848.183349915425;15013.924930375279;15225.77161342603;12673.493060277486;14068.874490040977;14712.337944792296;14743.410263589336;15033.398664053315;10786.406747628957;10873.927933144887;11046.226764126755;11077.72305692332;11238;10435.739106522398;10522.429020507776;12182.128957348323;12345.259284442962;12405 01:48:51 INFO - PROCESS | 1633 | __end_tp_report 01:48:51 INFO - PROCESS | 1633 | __start_cc_report 01:48:51 INFO - PROCESS | 1633 | _x_x_mozilla_cycle_collect,396 01:48:51 INFO - PROCESS | 1633 | __end_cc_report 01:48:51 INFO - PROCESS | 1633 | __startTimestamp1495010931734__endTimestamp 01:48:51 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | ------- Summary: start ------- 01:48:51 INFO - PROCESS | 1633 | Number of tests: 6 01:48:51 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | [#0] dojo.html Cycles:165 Average:6086.10 Median:6348.05 stddev:736.04 (11.6%) stddev-sans-first:691.82 01:48:51 INFO - PROCESS | 1633 | Values: 2804.4 3576.6 3604.8 3611.8 3637.7 5248.9 5373.6 6169.0 6188.8 6212.7 6195.9 6241.8 6251.4 6257.6 6275.1 5517.5 5559.1 5568.4 5570.0 5645.1 4729.3 5626.9 5645.2 5648.1 5681.6 5412.1 5422.2 5550.8 5619.1 5621.4 5501.6 5507.3 5515.7 5519.2 5524.0 4502.7 5379.4 5528.3 5530.0 5550.2 6280.5 6302.1 6304.2 6306.2 6321.5 5323.9 6152.1 6559.3 6576.2 6601.6 6376.3 6412.8 6517.7 6714.6 6722.0 6183.1 6228.8 6303.9 6323.1 6344.0 6791.5 6802.7 6859.7 6865.3 6871.0 6314.7 6326.1 6478.0 6483.5 6507.6 6531.2 6536.5 6591.7 6631.5 6633.5 6572.0 6572.0 6581.9 6583.5 6587.1 6271.5 6470.4 6511.9 6527.3 6568.7 5154.0 5170.9 5188.2 5260.6 5264.7 4582.4 4593.7 4630.4 4672.1 4718.3 6348.0 6361.1 6367.2 6373.2 6380.9 6582.5 6719.0 6729.3 6736.1 6744.9 6147.3 6179.5 6223.1 6300.5 6350.7 5906.6 5917.3 5917.5 5918.2 5943.6 6866.7 6883.2 6894.2 6907.5 6921.5 6373.5 6461.8 6557.1 6648.3 6742.1 4949.5 6521.8 6827.7 6875.1 6875.8 6499.7 6583.1 6584.3 6589.2 6590.2 6391.7 6414.3 6551.1 6668.9 6683.3 6478.4 6524.8 6526.2 6526.9 6541.5 5838.9 6209.0 6489.1 6497.4 6515.3 4818.5 6234.2 6236.8 6332.3 6393.0 6423.4 6427.9 6448.6 6496.9 6541.8 6054.7 6372.1 6383.0 6389.5 6412.3 01:48:51 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | [#1] ext.html Cycles:180 Average:56285.07 Median:3229.34 stddev:116053.05 (3593.7%) stddev-sans-first:116303.67 01:48:51 INFO - PROCESS | 1633 | Values: 738.4 863.1 900.2 920.3 969.3 32178.5 39260.0 39300.8 40602.0 40639.8 38797.2 38966.6 39148.0 39248.0 39262.8 7843.0 7970.8 8082.7 8222.8 8225.8 11593.2 11852.0 11858.7 11865.9 11881.1 11874.2 11930.5 11941.7 11967.0 11975.2 11778.7 11796.2 11822.3 11831.3 11838.0 11529.5 11544.7 11562.7 11569.6 11586.7 39712.0 39768.4 39813.0 39837.8 39871.6 4003.2 4019.6 4037.7 4097.0 4109.8 27865.9 28406.1 28467.9 28531.7 28993.4 3219.1 3239.6 3314.2 3362.3 3421.6 348491.0 349338.5 349401.0 349656.3 350461.0 44719.8 45284.0 53555.0 53576.7 53666.9 48189.5 54655.5 54993.7 55281.4 55343.7 13758.9 15147.4 15255.1 15562.1 15641.9 13233.5 13292.4 13314.1 13321.9 13376.1 10761.2 12364.4 12383.3 12396.4 12529.8 5177.2 5555.4 5813.6 5837.1 5838.0 819.9 878.2 903.8 911.7 924.0 22088.6 27813.4 28365.9 29185.6 29877.0 964.9 1100.6 1119.1 1138.7 1144.8 10315.0 12245.1 12505.2 14090.7 14343.3 502580.0 504252.0 504932.0 506505.0 507289.0 47409.0 47547.3 47595.8 47626.8 47802.8 1182.8 1494.1 1496.8 1502.2 1503.4 26278.2 26480.6 26509.3 26555.6 26918.5 38137.4 38236.8 38283.6 38367.0 38387.8 439311.0 439967.0 442067.0 442492.0 442506.0 29365.3 29520.6 29770.4 30174.4 31863.8 39745.8 39824.0 39993.0 40752.6 42020.0 23730.4 26696.2 28913.3 29472.3 29836.9 28773.0 28965.9 29384.7 29448.1 29731.3 33757.5 34151.7 34354.5 34646.7 34709.3 29121.7 29171.6 29407.0 29968.6 30134.7 22671.3 22962.9 23720.3 24618.4 24978.0 01:48:51 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | [#2] jquery.html Cycles:180 Average:42973.10 Median:14195.43 stddev:118167.49 (832.4%) stddev-sans-first:118455.80 01:48:51 INFO - PROCESS | 1633 | Values: 423.8 429.3 434.4 436.1 447.1 21871.3 22150.7 22638.0 22841.2 23042.2 23040.8 23627.4 23932.3 24021.5 24265.6 6763.8 6845.7 6962.5 7049.3 7126.5 6926.3 6964.9 7025.9 7690.3 8065.3 8049.6 8149.6 8163.7 8173.6 8174.7 7597.4 7868.5 7869.8 7877.6 7883.8 7330.2 7800.7 7874.3 7874.7 7890.4 25822.4 27088.5 27129.0 27335.7 27831.0 2568.5 2911.9 2930.2 2975.6 3025.3 13943.8 15577.7 16228.0 16990.9 17209.4 1740.9 2160.3 2165.9 2167.6 2181.1 156714.0 159733.0 165568.0 165689.0 169158.0 20708.4 22077.4 22272.9 22425.1 22492.4 32513.0 32758.2 33020.3 33238.3 33328.7 10559.4 10847.5 10937.3 10940.2 11130.3 8316.0 9097.4 9126.2 9297.0 9325.1 8316.4 9663.9 9881.8 10000.4 10136.4 4396.0 4455.4 4478.6 4522.5 4525.4 904.3 914.1 927.8 939.7 957.6 21488.4 22057.3 22140.0 22155.2 22206.2 848.8 955.8 962.2 988.4 993.2 11277.3 11379.0 11391.1 11394.0 11450.9 659769.0 683176.0 683537.0 737187.0 746081.0 133849.0 158327.0 164154.0 171281.0 172652.0 1246.7 1328.4 1342.9 1344.2 1350.6 19926.2 20256.5 20651.3 20719.0 20766.3 64178.0 64918.7 65443.7 65736.0 69916.0 96193.0 96636.0 96772.0 96926.0 98511.5 9058.7 11555.6 11588.3 11604.4 11900.0 13245.2 13290.9 13294.4 13705.9 13782.7 9853.9 11360.3 11718.6 11800.3 11896.7 8014.7 8835.4 10836.5 11077.9 11183.7 9895.7 10885.7 11670.1 11868.0 12104.6 9448.0 9719.6 11598.1 11806.2 11875.6 11565.1 11778.9 11802.0 11849.0 11946.2 01:48:51 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | [#3] mootools.html Cycles:180 Average:6902.79 Median:2449.24 stddev:4321.77 (176.5%) stddev-sans-first:4315.71 01:48:51 INFO - PROCESS | 1633 | Values: 1626.1 1646.4 1662.2 1669.8 1673.0 10932.9 11206.7 11258.2 11969.8 11971.8 10375.2 11148.2 11588.4 11656.2 11766.4 2415.1 2450.1 2457.2 2462.4 2468.6 2399.5 2407.1 2433.2 2447.9 2448.4 2379.1 2410.7 2430.7 2441.1 2473.5 2360.8 2409.8 2416.2 2421.7 2433.3 2396.8 2409.2 2422.5 2424.5 2429.4 11513.3 11659.6 11723.0 11743.9 11746.8 5812.0 5969.2 5977.9 6001.0 6009.1 3494.9 3609.0 3650.0 3667.7 3694.7 1244.4 1252.4 1277.3 1297.8 1298.0 14196.0 14560.6 15398.5 15519.2 15638.8 4569.6 4626.4 4755.0 4763.6 4765.5 12150.3 12232.1 12278.8 12285.3 12291.4 4734.9 5794.6 5825.1 5838.7 5864.7 5206.8 5248.6 5274.3 5282.6 5308.7 3915.9 3982.8 3987.5 4009.2 4079.2 1027.5 1031.3 1031.4 1041.0 1044.3 818.3 824.9 877.7 966.3 972.5 5732.9 6688.5 6840.1 6857.1 6878.6 950.5 1086.2 1094.6 1101.9 1110.3 4358.4 4420.8 4449.8 4550.3 4585.0 12568.8 13753.7 14385.6 14432.0 14432.3 13572.1 13757.7 14420.4 14423.0 14451.9 8499.7 9178.9 10267.4 10345.8 10420.9 6491.0 6907.6 7263.2 7276.3 7305.0 9936.5 12007.6 12058.8 12087.9 12129.6 13117.0 13136.6 13189.1 13208.7 13321.9 9494.1 9778.0 9782.9 9787.7 9794.3 10656.5 10684.4 10794.9 10840.2 10867.3 9109.3 9201.3 9205.9 9302.5 9396.7 8785.5 9031.2 9040.0 9065.3 9104.6 8002.8 8124.7 8127.6 8131.5 8140.3 5963.3 5970.2 6048.1 6062.9 6148.1 4863.8 5938.4 6100.5 6274.0 6369.1 01:48:51 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | [#4] prototype.html Cycles:180 Average:4776.69 Median:5195.16 stddev:1350.67 (26.0%) stddev-sans-first:1312.86 01:48:51 INFO - PROCESS | 1633 | Values: 344.8 388.5 389.6 403.3 403.4 4313.8 5128.5 5278.6 5331.1 5412.7 4715.8 4961.9 5417.4 5423.4 5443.3 3461.6 4398.9 4406.9 4460.7 4503.3 4085.0 4454.4 4459.3 4483.3 4508.3 3733.1 4348.9 4439.4 4464.5 4497.6 3839.4 3974.7 4064.8 4219.5 4487.6 3095.0 3868.4 4073.5 4086.9 4098.5 4241.9 4385.1 5190.7 5271.9 5286.3 4638.9 4644.8 5221.7 5226.8 5236.8 5145.0 5709.0 5727.7 5770.4 5791.4 3987.7 4638.3 4645.3 4660.9 4688.9 4963.5 4980.9 5994.7 6236.8 6274.8 4100.3 4429.6 4446.4 4762.4 4787.5 4836.8 4855.3 4857.5 4865.9 4925.9 4895.9 4996.0 5186.2 5199.6 5220.6 5736.0 5740.3 5745.3 5746.7 5832.0 1411.3 1529.4 1543.6 1617.5 1650.7 1004.7 1168.3 1170.8 1242.8 1251.3 4773.6 4822.9 4983.7 5343.5 5358.2 4931.0 5704.1 5900.9 5926.4 5932.8 4833.4 5237.7 5270.3 5319.6 5428.5 2864.9 2868.6 2963.7 3076.8 3099.4 5332.9 5463.6 5860.4 6076.4 6094.5 5040.6 5855.6 5907.9 6001.3 6066.8 5059.1 6022.1 6041.9 6185.4 6246.0 5252.8 5868.0 6092.7 6104.5 6125.6 5009.4 5031.1 5685.9 5739.3 5937.4 5209.5 5440.6 6206.5 6234.7 6308.4 4471.5 5217.3 5274.7 5279.4 5323.7 4839.8 5539.7 5662.3 5766.8 5992.6 5657.0 5835.5 5853.7 5886.6 5889.8 5225.8 5452.5 5477.6 5537.3 5817.0 5007.3 5128.2 5421.8 5527.8 5671.2 4968.7 5399.5 5640.0 5896.5 5897.6 4180.9 5049.3 5410.6 5597.3 5679.7 01:48:51 INFO - PROCESS | 1633 | 01:48:51 INFO - PROCESS | 1633 | [#5] yui.html Cycles:180 Average:20411.34 Median:16838.62 stddev:28158.61 (167.2%) stddev-sans-first:28198.88 01:48:51 INFO - PROCESS | 1633 | Values: 746.3 803.2 806.2 818.8 900.4 15899.0 16760.2 16917.0 17037.0 17144.5 12904.9 15793.4 15824.4 15834.0 15838.4 941.7 963.8 1012.3 1013.8 1014.1 1011.0 1014.6 1016.6 1016.8 1020.2 1063.8 1064.0 1065.1 1067.5 1069.5 1041.2 1048.1 1060.0 1060.7 1060.8 773.7 785.3 786.2 786.4 786.7 16063.9 16552.4 17184.6 17531.7 17554.4 14540.0 14635.6 14768.9 14904.5 15037.6 11881.8 12194.2 12269.8 12692.9 12792.6 3714.0 3865.2 3948.4 3958.2 3972.9 110044.0 111715.9 112136.0 112564.0 112904.0 11383.8 11500.0 11573.5 11832.4 11894.3 23690.6 23942.3 24321.0 24383.6 24476.5 8220.7 8227.1 8247.3 8294.8 8393.2 4673.3 4705.8 4738.6 4746.9 4757.3 3523.1 3591.5 3618.5 3633.3 3640.1 506.4 596.1 598.9 604.5 612.5 696.0 718.8 726.6 728.6 768.5 10682.7 10825.6 10910.6 11249.4 11326.7 695.5 728.2 739.7 750.8 752.2 4856.6 5044.2 5296.8 5298.4 5301.5 89921.6 92151.1 93377.0 93913.5 94110.1 62613.7 77944.6 78301.0 87188.0 89971.6 61497.0 62068.0 79446.2 80668.0 81705.8 9827.2 11070.9 11160.2 11417.9 11556.4 50466.2 51224.0 52197.7 52735.0 52782.7 64449.4 64985.7 65042.3 65236.3 65356.4 13122.5 13281.0 13436.4 13474.7 14008.3 15289.8 15448.9 15463.0 15507.7 16098.4 13916.7 13953.2 14244.8 14328.4 14347.2 11491.9 11985.0 14848.2 15013.9 15225.8 12673.5 14068.9 14712.3 14743.4 15033.4 10786.4 10873.9 11046.2 11077.7 11238.0 10435.7 10522.4 12182.1 12345.3 12405.0 01:48:51 INFO - PROCESS | 1633 | -------- Summary: end -------- 01:48:51 INFO - PROCESS | 1633 | 01:48:52 INFO - TEST-INFO | 1633: exit 0 01:48:52 INFO - TEST-OK | dromaeo_css | took 1115553ms 01:48:52 INFO - TEST-START | kraken 01:48:52 INFO - operating with platform_type : mac_ 01:48:52 INFO - Initialising browser for kraken test... 01:48:52 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp_Jx2G6/profile http://localhost:49225/getInfo.html) 01:48:54 INFO - PROCESS | 1645 | 2017-05-17 01:48:54.654 plugin-container[1647:10269] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 01:48:54 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:48:54 INFO - PROCESS | 1645 | 2017-05-17 01:48:54.654 plugin-container[1647:10269] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 01:48:54 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:48:54 INFO - PROCESS | 1645 | 2017-05-17 01:48:54.654 plugin-container[1647:10269] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:48:54 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 01:48:54 INFO - PROCESS | 1645 | colorDepth:24 01:48:54 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 01:48:54 INFO - PROCESS | 1645 | __metrics 01:48:54 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 01:48:55 INFO - TEST-INFO | 1645: exit 0 01:48:55 INFO - Browser initialized. 01:48:55 INFO - Running cycle 1/1 for kraken test... 01:48:55 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp_Jx2G6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:48:57 INFO - PROCESS | 1648 | 2017-05-17 01:48:57.586 plugin-container[1649:10400] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 01:48:57 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:48:57 INFO - PROCESS | 1648 | 2017-05-17 01:48:57.587 plugin-container[1649:10400] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 01:48:57 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:48:57 INFO - PROCESS | 1648 | 2017-05-17 01:48:57.587 plugin-container[1649:10400] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:49:07 INFO - PROCESS | 1648 | RSS: Main: 174342144 01:49:07 INFO - PROCESS | 1648 | 01:49:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 01:49:11 INFO - PROCESS | 1648 | RSS: Main: 187674624 01:49:11 INFO - PROCESS | 1648 | 01:49:13 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 01:49:14 INFO - PROCESS | 1648 | RSS: Main: 195682304 01:49:14 INFO - PROCESS | 1648 | 01:49:17 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 01:49:18 INFO - PROCESS | 1648 | RSS: Main: 199417856 01:49:18 INFO - PROCESS | 1648 | 01:49:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 01:49:21 INFO - PROCESS | 1648 | RSS: Main: 200687616 01:49:21 INFO - PROCESS | 1648 | 01:49:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:49:23 INFO - PROCESS | 1648 | RSS: Main: 200765440 01:49:23 INFO - PROCESS | 1648 | 01:49:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 01:49:27 INFO - PROCESS | 1648 | RSS: Main: 204943360 01:49:27 INFO - PROCESS | 1648 | 01:49:30 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 01:49:30 INFO - PROCESS | 1648 | RSS: Main: 204615680 01:49:30 INFO - PROCESS | 1648 | 01:49:34 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 01:49:34 INFO - PROCESS | 1648 | RSS: Main: 204902400 01:49:34 INFO - PROCESS | 1648 | 01:49:36 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:49:37 INFO - PROCESS | 1648 | RSS: Main: 204193792 01:49:37 INFO - PROCESS | 1648 | 01:49:38 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:49:39 INFO - PROCESS | 1648 | RSS: Main: 204230656 01:49:39 INFO - PROCESS | 1648 | 01:49:41 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:49:42 INFO - PROCESS | 1648 | RSS: Main: 204230656 01:49:42 INFO - PROCESS | 1648 | 01:49:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:49:45 INFO - PROCESS | 1648 | RSS: Main: 204611584 01:49:45 INFO - PROCESS | 1648 | 01:49:48 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:49:49 INFO - PROCESS | 1648 | RSS: Main: 205156352 01:49:49 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 01:49:51 INFO - PROCESS | 1648 | RSS: Main: 206712832 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | __start_tp_report 01:49:51 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 01:49:51 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 01:49:51 INFO - PROCESS | 1648 | |i|pagename|runs| 01:49:51 INFO - PROCESS | 1648 | |0;ai-astar;106;147;179;148;120;153;150;118;99;174 01:49:51 INFO - PROCESS | 1648 | |1;audio-beat-detection;140;113;125;138;95;97;133;142;131;141 01:49:51 INFO - PROCESS | 1648 | |2;audio-dft;200;187;193;216;275;197;224;224;195;196 01:49:51 INFO - PROCESS | 1648 | |3;audio-fft;83;59;62;87;63;67;59;81;73;59 01:49:51 INFO - PROCESS | 1648 | |4;audio-oscillator;88;70;85;74;74;91;115;102;85;79 01:49:51 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;89;106;88;88;78;134;119;88;109;87 01:49:51 INFO - PROCESS | 1648 | |6;imaging-darkroom;96;96;97;95;95;96;95;105;95;96 01:49:51 INFO - PROCESS | 1648 | |7;imaging-desaturate;94;93;92;76;149;150;115;145;102;149 01:49:51 INFO - PROCESS | 1648 | |8;json-parse-financial;66;66;65;70;68;69;68;68;70;69 01:49:51 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;60;65;61;78;56;53;65;90;86;68 01:49:51 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;82;82;84;84;82;84;85;90;83;102 01:49:51 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;136;137;136;130;134;137;144;145;156;139 01:49:51 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;169;167;163;165;168;165;163;170;165;166 01:49:51 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;64;65;63;66;64;65;72;80;82;84 01:49:51 INFO - PROCESS | 1648 | __end_tp_report 01:49:51 INFO - PROCESS | 1648 | __start_cc_report 01:49:51 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,979 01:49:51 INFO - PROCESS | 1648 | __end_cc_report 01:49:51 INFO - PROCESS | 1648 | __startTimestamp1495010991291__endTimestamp 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | ------- Summary: start ------- 01:49:51 INFO - PROCESS | 1648 | Number of tests: 14 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:139.40 Median:151.50 stddev:27.44 (18.1%) stddev-sans-first:26.31 01:49:51 INFO - PROCESS | 1648 | Values: 106.0 147.0 179.0 148.0 120.0 153.0 150.0 118.0 99.0 174.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:140.50 stddev:17.84 (12.7%) stddev-sans-first:18.13 01:49:51 INFO - PROCESS | 1648 | Values: 140.0 113.0 125.0 138.0 95.0 97.0 133.0 142.0 131.0 141.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:210.70 Median:208.00 stddev:26.15 (12.6%) stddev-sans-first:27.45 01:49:51 INFO - PROCESS | 1648 | Values: 200.0 187.0 193.0 216.0 275.0 197.0 224.0 224.0 195.0 196.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:69.30 Median:70.00 stddev:10.89 (15.6%) stddev-sans-first:10.37 01:49:51 INFO - PROCESS | 1648 | Values: 83.0 59.0 62.0 87.0 63.0 67.0 59.0 81.0 73.0 59.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:86.30 Median:82.00 stddev:13.82 (16.9%) stddev-sans-first:14.65 01:49:51 INFO - PROCESS | 1648 | Values: 88.0 70.0 85.0 74.0 74.0 91.0 115.0 102.0 85.0 79.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:87.50 stddev:17.70 (20.2%) stddev-sans-first:18.43 01:49:51 INFO - PROCESS | 1648 | Values: 89.0 106.0 88.0 88.0 78.0 134.0 119.0 88.0 109.0 87.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:96.60 Median:96.00 stddev:3.03 (3.2%) stddev-sans-first:3.20 01:49:51 INFO - PROCESS | 1648 | Values: 96.0 96.0 97.0 95.0 95.0 96.0 95.0 105.0 95.0 96.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:116.50 Median:113.00 stddev:28.98 (25.6%) stddev-sans-first:29.57 01:49:51 INFO - PROCESS | 1648 | Values: 94.0 93.0 92.0 76.0 149.0 150.0 115.0 145.0 102.0 149.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:68.50 stddev:1.73 (2.5%) stddev-sans-first:1.69 01:49:51 INFO - PROCESS | 1648 | Values: 66.0 66.0 65.0 70.0 68.0 69.0 68.0 68.0 70.0 69.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:66.50 stddev:12.51 (18.8%) stddev-sans-first:12.91 01:49:51 INFO - PROCESS | 1648 | Values: 60.0 65.0 61.0 78.0 56.0 53.0 65.0 90.0 86.0 68.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:84.00 stddev:6.16 (7.3%) stddev-sans-first:6.38 01:49:51 INFO - PROCESS | 1648 | Values: 82.0 82.0 84.0 84.0 82.0 84.0 85.0 90.0 83.0 102.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:138.00 stddev:7.31 (5.3%) stddev-sans-first:7.64 01:49:51 INFO - PROCESS | 1648 | Values: 136.0 137.0 136.0 130.0 134.0 137.0 144.0 145.0 156.0 139.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.50 stddev:2.38 (1.4%) stddev-sans-first:2.28 01:49:51 INFO - PROCESS | 1648 | Values: 169.0 167.0 163.0 165.0 168.0 165.0 163.0 170.0 165.0 166.0 01:49:51 INFO - PROCESS | 1648 | 01:49:51 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:69.00 stddev:8.36 (12.1%) stddev-sans-first:8.53 01:49:51 INFO - PROCESS | 1648 | Values: 64.0 65.0 63.0 66.0 64.0 65.0 72.0 80.0 82.0 84.0 01:49:51 INFO - PROCESS | 1648 | -------- Summary: end -------- 01:49:51 INFO - PROCESS | 1648 | 01:49:52 INFO - TEST-INFO | 1648: exit 0 01:49:52 INFO - TEST-OK | kraken | took 59787ms 01:49:52 INFO - SUITE-END | took 1175s 01:49:52 INFO - Completed test suite (00:19:35) 01:49:52 INFO - javascript benchmark 01:49:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7925.439398888331, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2804.3830357321385, 3576.6423357664235, 3604.819759012049, 3611.78329300242, 3637.7453578249524, 5248.89502209956, 5373.623846330758, 6168.969155154224, 6188.845278868031, 6212.65830379329, 6195.938040619592, 6241.843953901143, 6251.374862513746, 6257.624542527452, 6275.090111933767, 5517.503424691787, 5559.082751346023, 5568.387477377485, 5569.972150139243, 5645.096784514479, 4729.290606409032, 5626.943730562703, 5645.2096706461125, 5648.096304591266, 5681.602287839833, 5412.079946409101, 5422.15956526739, 5550.833474995757, 5619.072852979253, 5621.353544342392, 5501.614886957915, 5507.339119305669, 5515.7242137892945, 5519.172124181365, 5523.972380138073, 4502.707324023928, 5379.408265090837, 5528.336599608073, 5529.976954263434, 5550.16747487876, 6280.528960327993, 6302.149209856657, 6304.243490781089, 6306.243250809932, 6321.494280457552, 5323.935212957385, 6152.107944348045, 6559.311272316386, 6576.243731970837, 6601.570897891622, 6376.266729326094, 6412.839679008015, 6517.674116294166, 6714.563553369016, 6721.966390168018, 6183.134361189437, 6228.844278893019, 6303.9054414183365, 6323.083152942798, 6343.9682801585695, 6791.456683465311, 6802.65986700663, 6859.691313890887, 6865.313468653095, 6871.03805467235, 6314.747410103636, 6326.145970294044, 6478.028295755675, 6483.513736469784, 6507.609543427409, 6531.248906375733, 6536.542442029107, 6591.736330546821, 6631.535792494479, 6633.535652504375, 6571.967140164268, 6572.047053177263, 6581.86836263272, 6583.539152259392, 6587.143671322698, 6271.466925311308, 6470.38531339522, 6511.902321465182, 6527.281998980108, 6568.67156642166, 5153.974230128825, 5170.896582068338, 5188.169892817131, 5260.579153667774, 5264.710440925709, 4582.404287442678, 4593.678442508993, 4630.4443466784205, 4672.08894265615, 4718.339432479455, 6348.047792831112, 6361.109444677749, 6367.172267605181, 6373.203349581302, 6380.904286435707, 6582.506312026617, 6719.0, 6729.2934241903895, 6736.05695202672, 6744.865102697919, 6147.293061297919, 6179.5056395488255, 6223.1287619733275, 6300.495960323163, 6350.714217860207, 5906.616069955439, 5917.31950825652, 5917.497012753965, 5918.201042859245, 5943.613665111754, 6866.656667166621, 6883.174019117738, 6894.207166175954, 6907.481938854707, 6921.480888933451, 6373.521985850987, 6461.8061458156335, 6557.082008518716, 6648.335166483506, 6742.08981787472, 4949.480304567968, 6521.771737989147, 6827.658617069226, 6875.106236189264, 6875.7937261882225, 6499.6750162492635, 6583.111279977142, 6584.308647592125, 6589.24223714279, 6590.176227971504, 6391.6804159792755, 6414.294427612866, 6551.148350714377, 6668.933310666831, 6683.331666833278, 6478.449331806661, 6524.771632992823, 6526.184226971629, 6526.902096468461, 6541.476681865344, 5838.883222335615, 6208.9689551551955, 6489.059086432535, 6497.415232629086, 6515.315891831289, 4818.494058123846, 6234.220722409699, 6236.781712640036, 6332.271788744353, 6393.0, 6423.42189202974, 6427.871442571029, 6448.613083215022, 6496.9025464617125, 6541.803745887631, 6054.698239878448, 6372.0760489729655, 6383.0, 6389.4888408926245, 6412.294647588668], "value": 6038.288084264423, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.3613174603967, 863.1325517854557, 900.2213085680888, 920.3097676742439, 969.2827307792236, 32178.517322240157, 39259.99999999996, 39300.803495982516, 40602.0, 40639.79680101595, 38797.22405551887, 38966.61033389665, 39148.0, 39248.01879953003, 39262.82211533659, 7842.960785196066, 7970.840583188362, 8082.67669293229, 8222.753317400488, 8225.79435514111, 11593.246438981481, 11852.0, 11858.703532411673, 11865.881341186607, 11881.108916831228, 11874.22817516863, 11930.522779088826, 11941.701457463547, 11966.982806461461, 11975.221610595327, 11778.705532361673, 11796.174267801258, 11822.349770762605, 11831.349275789828, 11838.0, 11529.538818447252, 11544.653660390202, 11562.653120406401, 11569.595064172714, 11586.710332241677, 39712.0, 39768.40347394792, 39813.0, 39837.80081099605, 39871.60128398708, 4003.1793482336343, 4019.622417125036, 4037.6769858411258, 4097.016715988141, 4109.794510274504, 27865.86067069652, 28406.147815565568, 28467.857660711772, 28531.714682853322, 28993.42013159746, 3219.0825614699697, 3239.5950506186728, 3314.2045908981913, 3362.29391827714, 3421.606515250753, 348491.0, 349338.5066149357, 349400.99999999744, 349656.2517187423, 350461.0, 44719.776401117786, 45284.0, 53555.0, 53576.73211633995, 53666.92666146733, 48189.5181048192, 54655.453445465035, 54993.72503137459, 55281.44718552763, 55343.72328138414, 13758.862411375758, 15147.394104235707, 15255.08469491834, 15562.144082075369, 15641.921790390976, 13233.470661173445, 13292.401841917137, 13314.068015239034, 13321.934245260356, 13376.130551514121, 10761.246712730033, 12364.381780911099, 12383.318917459626, 12396.442160102817, 12529.812052819216, 5177.223416487482, 5555.388907220203, 5813.593048486566, 5837.066069428871, 5838.0, 819.8934138562068, 878.1876763993281, 903.7650210945202, 911.7401540560908, 923.9538023098953, 22088.558228835333, 27813.443731125266, 28365.858170709016, 29185.56221656677, 29877.0, 964.9228061755043, 1100.6367898593444, 1119.1031202112504, 1138.698244965085, 1144.7710457908452, 10315.0, 12245.142840001292, 12505.249685018927, 14090.71818563623, 14343.282835858165, 502580.0, 504252.0, 504932.0, 506505.0, 507289.0, 47409.0, 47547.28679069817, 47595.76202118967, 47626.76186619114, 47802.76098619554, 1182.822576613515, 1494.0512774388342, 1496.835348111685, 1502.1512845242403, 1503.3535579700833, 26278.21165365042, 26480.602790957764, 26509.33726656849, 26555.601665975413, 26918.461630767666, 38137.427938581495, 38236.808815955745, 38283.61716382801, 38367.0, 38387.84836454911, 439311.0, 439967.0, 442067.0, 442492.0, 442506.0, 29365.265868352606, 29520.557191641714, 29770.40459190761, 30174.396512069197, 31863.84068079645, 39745.80127099346, 39824.0, 39993.0, 40752.59247407607, 42020.0, 23730.406739831644, 26696.19911402661, 28913.277168070126, 29472.263193420335, 29836.850815745784, 28773.0, 28965.85517072401, 29384.706152938197, 29448.11655650334, 29731.256718582208, 33757.49363759496, 34151.658483414845, 34354.48468272928, 34646.65353346535, 34709.305813884086, 29121.7087829119, 29171.562426563192, 29407.0, 29968.550471743376, 30134.69865301319, 22671.319860404215, 22962.885185573967, 23720.288391348287, 24618.384540385916, 24978.0], "value": 18599.78690803217, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.83894120234305, 429.31309904153346, 434.4287213281947, 436.1364498293378, 447.0656328273909, 21871.343859684195, 22150.66773998389, 22638.0, 22841.200557980475, 23042.193523226648, 23040.769592304074, 23627.40931476714, 23932.282031539038, 24021.519569608598, 24265.636015459782, 6763.830904227384, 6845.6919438625255, 6962.477814163958, 7049.295070492961, 7126.536775109627, 6926.341997510229, 6964.930350696479, 7025.929740702604, 7690.3078722914925, 8065.2741253287195, 8049.637766300501, 8149.633266502992, 8163.6734530618705, 8173.5504547249875, 8174.6730130794995, 7597.354224890863, 7868.52788832669, 7869.8032549186155, 7877.60611969405, 7883.763487095368, 7330.193678695339, 7800.687972481094, 7874.251946065115, 7874.685012599489, 7890.38247159331, 25822.354441138934, 27088.458230835273, 27129.0, 27335.726642733516, 27831.0, 2568.5119827232766, 2911.878926613248, 2930.1795497260587, 2975.5685425613383, 3025.3041800385813, 13943.790843137362, 15577.688446231012, 16228.026318420933, 16990.915045424816, 17209.397671081577, 1740.9390671326569, 2160.2979031814566, 2165.945851353713, 2167.642339014061, 2181.083944743196, 156714.0, 159733.0, 165568.0, 165689.0, 169158.0, 20708.378748637566, 22077.448063798533, 22272.88863555672, 22425.10299587998, 22492.437689057904, 32513.0, 32758.181045474055, 33020.339593208, 33238.3352332952, 33328.66671333255, 10559.366438013743, 10847.51186196623, 10937.343759374464, 10940.179486538382, 11130.276532025393, 8316.0, 9097.40866843653, 9126.178643921947, 9297.023812499725, 9325.114114159143, 8316.41785075039, 9663.855042174373, 9881.75295617601, 10000.449975251431, 10136.391816491034, 4395.978020109943, 4455.440595791447, 4478.641708663299, 4522.540256703624, 4525.411696479437, 904.3036861616519, 914.1133100892124, 927.7634203278255, 939.6617217801586, 957.6169532187181, 21488.355349339545, 22057.33827985163, 22140.0, 22155.224567140074, 22206.222782202545, 848.7793173774863, 955.8375076237056, 962.1629182611033, 988.4365911430416, 993.1558175550724, 11277.323360598226, 11379.0, 11391.088712902947, 11394.031507321804, 11450.885491145149, 659769.0, 683176.0, 683537.0, 737187.0, 746081.0, 133849.0, 158327.0, 164154.0, 171281.0, 172652.0, 1246.7007918099773, 1328.35574746248, 1342.8992825538123, 1344.1934839096464, 1350.6015725361042, 19926.20295188176, 20256.493587660425, 20651.27720529774, 20719.0, 20766.273180438613, 64178.0, 64918.675406624556, 65443.67278163579, 65736.0139597916, 69916.0, 96193.0, 96636.0, 96772.0, 96926.0, 98511.52232716659, 9058.682946096855, 11555.595554155565, 11588.304701717923, 11604.361760103275, 11900.04799616046, 13245.20528768277, 13290.867091328962, 13294.40175192138, 13705.931470342983, 13782.65543361424, 9853.852192216978, 11360.318380897173, 11718.648440546796, 11800.291982481078, 11896.702582435508, 8014.719484818003, 8835.425697329652, 10836.512356944115, 11077.944610276896, 11183.72040698989, 9895.65365212214, 10885.74813896412, 11670.066394688234, 11868.0, 12104.576339828063, 9447.952760236154, 9719.611215551298, 11598.130140239684, 11806.232594881305, 11875.643730688093, 11565.132615054074, 11778.941105294418, 11802.0, 11848.99283560907, 11946.163768536118], "value": 12043.649687981759, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1626.1462732065672, 1646.448439772676, 1662.1938359895455, 1669.824668409817, 1672.9749053764197, 10932.945335273313, 11206.719832004204, 11258.155638327118, 11969.820452693217, 11971.820422693656, 10375.221858360615, 11148.163887708413, 11588.42057897106, 11656.242344247617, 11766.411679416038, 2415.0943396226457, 2450.1179575352858, 2457.1891275878916, 2462.3597864555168, 2468.5556599812026, 2399.4601214726636, 2407.0691612775863, 2433.1605596069326, 2447.877606119696, 2448.3634255093716, 2379.0721618568773, 2410.6986626671664, 2430.723869968263, 2441.084593277521, 2473.51766405551, 2360.8229382796358, 2409.771071748181, 2416.2147302126878, 2421.7457166997474, 2433.2700189943034, 2396.760323967607, 2409.217004473553, 2422.467057247403, 2424.527217192648, 2429.3683642252963, 11513.309201447857, 11659.591914282959, 11723.0, 11743.94128029363, 11746.823797643043, 5812.0, 5969.164316995624, 5977.8804423911715, 6001.009833377488, 6009.128676341949, 3494.877679281238, 3609.0, 3649.9635003649887, 3667.7249206309366, 3694.6674799268194, 1244.4337826289018, 1252.3738130934532, 1277.2783377391745, 1297.753426848905, 1298.039450806406, 14196.0, 14560.563183104523, 15398.46105386306, 15519.224038798127, 15638.843611563852, 4569.588737013684, 4626.352310676508, 4754.95245047552, 4763.642726795504, 4765.499622539653, 12150.270983740827, 12232.143749937597, 12278.754424911453, 12285.324307163191, 12291.385430728427, 4734.905301893943, 5794.594378393472, 5825.067989121805, 5838.7080645967535, 5864.736086876015, 5206.817761378409, 5248.6325957182635, 5274.340707411574, 5282.6302158849285, 5308.681479111266, 3915.8629447969183, 3982.78094704794, 3987.501562304712, 4009.1981603679383, 4079.204555111784, 1027.4913917610681, 1031.2781053262745, 1031.4275577054657, 1041.0, 1044.2794471814423, 818.3044412249659, 824.9092599814015, 877.6884206106868, 966.3042609321277, 972.4700038479045, 5732.856678583069, 6688.498362622823, 6840.076589660432, 6857.074294970115, 6878.621675807779, 950.50098698184, 1086.1582273737758, 1094.6168840905777, 1101.8567586213742, 1110.3448965110624, 4358.433403657505, 4420.778961051935, 4449.822007119679, 4550.271956486946, 4585.0, 12568.811467827807, 13753.656158595915, 14385.64035899111, 14432.0, 14432.278386080654, 13572.117812342167, 13757.724845503033, 14420.42318307277, 14423.0, 14451.92774036123, 8499.702510412106, 9178.908210917938, 10267.383956962456, 10345.793084138275, 10420.895791042145, 6491.0, 6907.585544867324, 7263.237360077116, 7276.345128938421, 7305.013823134022, 9936.453495057842, 12007.579734709243, 12058.819117713418, 12087.879121208676, 12129.575464858688, 13117.016223783063, 13136.60590182296, 13189.076764626385, 13208.735825283633, 13321.933390332986, 9494.0980606845, 9778.0, 9782.853257201004, 9787.706368808947, 9794.26543009263, 10656.467176641292, 10684.412357320422, 10794.892051079389, 10840.186985975926, 10867.29362591429, 9109.271258299188, 9201.263898888206, 9205.86191207119, 9302.488363140092, 9396.67111651089, 8785.516796575994, 9031.232345250728, 9039.954800225956, 9065.32010099232, 9104.590293436679, 8002.799930001796, 8124.67501299965, 8127.634256458356, 8131.512109273463, 8140.348772098337, 5963.3142188647125, 5970.223870896929, 6048.123022161674, 6062.878742425215, 6148.077788331607, 4863.829765958175, 5938.406159384027, 6100.481459076028, 6274.023682539985, 6369.108324834619], "value": 5260.2343787765385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.8086312096786, 388.45616137407626, 389.61038961038963, 403.334498078171, 403.3747691078829, 4313.762743049131, 5128.512791284831, 5278.5513231375335, 5331.054165229692, 5412.729363531817, 4715.834945776898, 4961.875953101175, 5417.431169727181, 5423.430539793324, 5443.319585051869, 3461.567304086989, 4398.912021759563, 4406.911861762763, 4460.66545009124, 4503.256962601167, 4084.9591504084874, 4454.398656181423, 4459.286514157736, 4483.282674772037, 4508.278675411935, 3733.1040550268012, 4348.869533913972, 4439.400680908085, 4464.508904020555, 4497.64018878489, 3839.3856982882885, 3974.662153716923, 4064.7764372959477, 4219.45147130871, 4487.640988720895, 3095.034652995364, 3868.439076333944, 4073.5111786585503, 4086.856960006415, 4098.467199264106, 4241.936370954438, 4385.101054283896, 5190.740462976836, 5271.894562108775, 5286.312779338661, 4638.907221855544, 4644.814207431698, 5221.660592061542, 5226.830865190941, 5236.7905283788605, 5144.993452553507, 5708.97145514274, 5727.713614319267, 5770.422957704196, 5791.420857914217, 3987.6809855211513, 4638.327442520849, 4645.349651048856, 4660.906781864343, 4688.906221875543, 4963.528464795875, 4980.9252861207115, 5994.730237139339, 6236.750529978795, 6274.811755647338, 4100.34394496879, 4429.579189976947, 4446.421965144576, 4762.357081793983, 4787.521247875185, 4836.830710925101, 4855.32025516428, 4857.489963553776, 4865.9319789089495, 4925.852224433273, 4895.912309651565, 4995.952468993911, 5186.196139598368, 5199.584033277329, 5220.634555581149, 5735.971320143456, 5740.311162660508, 5745.328422283658, 5746.741396637161, 5832.005076522596, 1411.336671764269, 1529.4035326222781, 1543.575516732885, 1617.5147455763224, 1650.6877032758985, 1004.6584161385164, 1168.258156070884, 1170.7599942011866, 1242.7514497100617, 1251.3242848861512, 4773.570378665867, 4822.855314340575, 4983.651144419856, 5343.488560376617, 5358.223057656618, 4930.950690493121, 5704.11586204139, 5900.878833021712, 5926.407359264125, 5932.79235226765, 4833.4441539222735, 5237.685738855681, 5270.284449463119, 5319.627626066215, 5428.4842939920645, 2864.8854045837934, 2868.584055311968, 2963.748081413061, 3076.8153910765427, 3099.411111888734, 5332.9466705333225, 5463.562914966793, 5860.384659610765, 6076.392360763888, 6094.537311045317, 5040.647154699209, 5855.648661080349, 5907.881842363128, 6001.309849367379, 6066.75732970685, 5059.1163037069255, 6022.126791615191, 6041.939580604226, 6185.350538193425, 6245.968770156211, 5252.7898884045735, 5867.970660146672, 6092.664903430354, 6104.481119104926, 6125.632462052291, 5009.41262240153, 5031.144705400017, 5685.886282274414, 5739.282589676291, 5937.376575459687, 5209.541328428047, 5440.646357986719, 6206.503479721701, 6234.688265586793, 6308.432241098323, 4471.485779135441, 5217.278298161522, 5274.657147285414, 5279.445658205987, 5323.733813309396, 4839.830605928775, 5539.695316757617, 5662.2639056922335, 5766.769329226951, 5992.580519363602, 5657.038303488499, 5835.533157347487, 5853.707314634336, 5886.6173698709445, 5889.793857214977, 5225.843224703265, 5452.5365343946205, 5477.616566840283, 5537.335519737658, 5817.0, 5007.29606012749, 5128.230765385073, 5421.807202415463, 5527.803864367482, 5671.206031155724, 4968.701877887338, 5399.541039011728, 5640.0, 5896.528277737685, 5897.557683173865, 4180.937285940652, 5049.2930989660745, 5410.567154627699, 5597.272354593877, 5679.716014199191], "value": 4381.131321655164, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.2947514598707, 803.1927912447989, 806.234077626255, 818.7666515043214, 900.4012331799352, 15899.04605723656, 16760.245788939497, 16917.0, 17036.999999999985, 17144.54271386932, 12904.870951290484, 15793.447229346975, 15824.367025318974, 15834.0, 15838.366465341373, 941.7080704981461, 963.7976025034716, 1012.3470361616753, 1013.7921726046179, 1014.1025192704473, 1011.039512463163, 1014.6093753904726, 1016.6492560066802, 1016.8474728790703, 1020.2042406922612, 1063.8191507443714, 1063.9785805626607, 1065.1265961911236, 1067.4769363012108, 1069.5080263078953, 1041.1774697988617, 1048.09863517375, 1060.0459586372288, 1060.671191930504, 1060.7613287010438, 773.6595897805004, 785.3442375616368, 786.182370334851, 786.3748320085546, 786.7403756760256, 16063.91968040164, 16552.42066527678, 17184.57038574033, 17531.73702394465, 17554.385596504188, 14539.982201245917, 14635.560933172022, 14768.852311476856, 14904.478343257933, 15037.624059398493, 11881.762364752743, 12194.207376520586, 12269.815952760715, 12692.936535317263, 12792.616221513277, 3713.9814300928592, 3865.246276975991, 3948.4077388391684, 3958.2083583283456, 3972.9006774830573, 110044.0, 111715.88284117136, 112136.0, 112564.0, 112904.0, 11383.817220393063, 11500.0, 11573.479193436315, 11832.408379581158, 11894.345810980307, 23690.644640330407, 23942.281731548082, 24321.0, 24383.634245486333, 24476.51046979086, 8220.671173153127, 8227.095019547845, 8247.257746802816, 8294.83410331802, 8393.202645748644, 4673.299005149255, 4705.788239529229, 4738.573528382462, 4746.905061898743, 4757.317894841927, 3523.119220194951, 3591.479235510836, 3618.457231415256, 3633.327834350601, 3640.12636967126, 506.3822136992863, 596.1385797522624, 598.8682489852225, 604.5133667397699, 612.4793925163665, 695.9681502735252, 718.8418547919449, 726.6003697966203, 728.5519405565645, 768.5043147170156, 10682.73293167677, 10825.566977320976, 10910.618128365628, 11249.43752812356, 11326.716832079264, 695.4714417043084, 728.1953441447231, 739.6782399656166, 750.7897788619196, 752.2477522477523, 4856.587190088882, 5044.167712327425, 5296.761645725952, 5298.3906850712665, 5301.496357845998, 89921.55039224762, 92151.0784892156, 93377.0, 93913.5304323474, 94110.0588994115, 62613.68693156505, 77944.61027694825, 78301.0, 87188.0, 89971.55014225018, 61497.0, 62068.0, 79446.20553794388, 80668.0, 81705.77441338384, 9827.213822894295, 11070.944645276722, 11160.218784684994, 11417.942910285396, 11556.422178890854, 50466.2430063542, 51224.0, 52197.7390113047, 52735.0, 52782.736086319324, 64449.355506444335, 64985.67507162434, 65042.34957650363, 65236.34763652303, 65356.36609084811, 13122.540711075448, 13281.0, 13436.395362208528, 13474.663133421744, 14008.299585020912, 15289.77065344043, 15448.922755386151, 15462.994905331117, 15507.689846203239, 16098.436554720529, 13916.721665566834, 13953.232572208626, 14244.786328204878, 14328.355224015153, 14347.210903399995, 11491.885081149081, 11985.041196704069, 14848.183349915425, 15013.924930375279, 15225.77161342603, 12673.493060277486, 14068.874490040977, 14712.337944792296, 14743.410263589336, 15033.398664053315, 10786.406747628957, 10873.927933144887, 11046.226764126755, 11077.72305692332, 11238.0, 10435.739106522398, 10522.429020507776, 12182.128957348323, 12345.259284442962, 12405.0], "value": 7949.31958556528, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 147.0, 179.0, 148.0, 120.0, 153.0, 150.0, 118.0, 99.0, 174.0], "value": 139.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 113.0, 125.0, 138.0, 95.0, 97.0, 133.0, 142.0, 131.0, 141.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 187.0, 193.0, 216.0, 275.0, 197.0, 224.0, 224.0, 195.0, 196.0], "value": 210.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 62.0, 87.0, 63.0, 67.0, 59.0, 81.0, 73.0, 59.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 85.0, 74.0, 74.0, 91.0, 115.0, 102.0, 85.0, 79.0], "value": 86.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 106.0, 88.0, 88.0, 78.0, 134.0, 119.0, 88.0, 109.0, 87.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 95.0, 95.0, 96.0, 95.0, 105.0, 95.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 92.0, 76.0, 149.0, 150.0, 115.0, 145.0, 102.0, 149.0], "value": 116.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 70.0, 68.0, 69.0, 68.0, 68.0, 70.0, 69.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 61.0, 78.0, 56.0, 53.0, 65.0, 90.0, 86.0, 68.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 84.0, 84.0, 82.0, 84.0, 85.0, 90.0, 83.0, 102.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 136.0, 130.0, 134.0, 137.0, 144.0, 145.0, 156.0, 139.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 163.0, 165.0, 168.0, 165.0, 163.0, 170.0, 165.0, 166.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 66.0, 64.0, 65.0, 72.0, 80.0, 82.0, 84.0], "value": 70.5, "unit": "score"}]}]} 01:49:52 INFO - Return code: 0 01:49:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:49:52 ERROR - # TBPL SUCCESS # 01:49:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:49:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:49:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:49:52 INFO - 2017-05-17 01:49:52.596 screenresolution[1653:10783] starting screenresolution argv=screenresolution get 01:49:52 INFO - 2017-05-17 01:49:52.598 screenresolution[1653:10783] Display 0: 1600x1200x32@60 01:49:52 INFO - 2017-05-17 01:49:52.604 screenresolution[1654:10786] starting screenresolution argv=screenresolution list 01:49:52 INFO - Available Modes on Display 0 01:49:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:49:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:49:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:49:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:49:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:49:52 INFO - Intel Iris: 01:49:52 INFO - Chipset Model: Intel Iris 01:49:52 INFO - Type: GPU 01:49:52 INFO - Bus: Built-In 01:49:52 INFO - VRAM (Dynamic, Max): 1536 MB 01:49:52 INFO - Vendor: Intel (0x8086) 01:49:52 INFO - Device ID: 0x0a2e 01:49:52 INFO - Revision ID: 0x0009 01:49:52 INFO - Displays: 01:49:52 INFO - Display: 01:49:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:49:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:49:52 INFO - Display Serial Number: 1600x1200 60 01:49:52 INFO - Main Display: Yes 01:49:52 INFO - Mirror: Off 01:49:52 INFO - Online: Yes 01:49:52 INFO - Rotation: Supported 01:49:52 INFO - Return code: 0 01:49:52 INFO - Running post-action listener: _resource_record_post_action 01:49:52 INFO - [mozharness: 2017-05-17 08:49:52.841109Z] Finished run-tests step (success) 01:49:52 INFO - Running post-run listener: _resource_record_post_run 01:49:52 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 43870208; Write bytes: 428037120; Read time: 445; Write time: 1472 01:49:52 INFO - TinderboxPrint: CPU usage
54.5% 01:49:52 INFO - TinderboxPrint: I/O read bytes / time
43,870,208 / 445 01:49:52 INFO - TinderboxPrint: I/O write bytes / time
428,037,120 / 1,472 01:49:52 INFO - TinderboxPrint: CPU idle
2,168.2 (45.5%) 01:49:52 INFO - TinderboxPrint: CPU system
959.4 (20.1%) 01:49:52 INFO - TinderboxPrint: CPU user
1,638.1 (34.4%) 01:49:52 INFO - TinderboxPrint: Swap in / out
163,397,632 / 0 01:49:52 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119656960; Write bytes: 115951616; Read time: 10845; Write time: 298 01:49:52 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 41658368; Write bytes: 301018112; Read time: 415; Write time: 1135 01:49:53 INFO - Running post-run listener: _upload_blobber_files 01:49:53 INFO - Blob upload gear active. 01:49:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:49:53 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 01:49:53 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'] 01:49:53 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 01:49:53 INFO - (blobuploader) - INFO - Open directory for files ... 01:49:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:49:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:49:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:49:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:49:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:49:54 INFO - (blobuploader) - INFO - Done attempting. 01:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:49:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:49:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:49:55 INFO - (blobuploader) - INFO - Done attempting. 01:49:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:49:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:49:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:49:56 INFO - (blobuploader) - INFO - Done attempting. 01:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:49:58 INFO - (blobuploader) - INFO - Done attempting. 01:49:58 INFO - (blobuploader) - INFO - Iteration through files over. 01:49:58 INFO - Return code: 0 01:49:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:49:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:49:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/069b1e88dc9ab0251c406f5c42fefe209303a2d9110e01cee1aaafb82106acbe852d26be6cfda8363c22879ffd8f8c171539538c43d0dc1cad96707a44ed5663", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d36c457346ffb6eeeed158f52df8fd2145c1079a54db87006f0ce4bf3c6b4c80bbe5bacd3b5f4ff8450e260894878e81e253bcc0fa056f3b43bc1da947b973d9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5ca60493dbc669bdb462299407ebadea07cc24018e5f6cbfc2d9c3d61a16897f29cf4e4d84f1be1965fab6482e2a939a259386294b411ad545c5dca2f206f13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f15dadf0bc575d9f3030d2781164281d82f207915e621a0055d7ad492be3e81a3d9912458ceb84649afa8df5672ca993e27c905b24e1a0d505d8d17927661a5"} 01:49:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:49:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:49:58 INFO - Contents: 01:49:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/069b1e88dc9ab0251c406f5c42fefe209303a2d9110e01cee1aaafb82106acbe852d26be6cfda8363c22879ffd8f8c171539538c43d0dc1cad96707a44ed5663", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d36c457346ffb6eeeed158f52df8fd2145c1079a54db87006f0ce4bf3c6b4c80bbe5bacd3b5f4ff8450e260894878e81e253bcc0fa056f3b43bc1da947b973d9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5ca60493dbc669bdb462299407ebadea07cc24018e5f6cbfc2d9c3d61a16897f29cf4e4d84f1be1965fab6482e2a939a259386294b411ad545c5dca2f206f13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f15dadf0bc575d9f3030d2781164281d82f207915e621a0055d7ad492be3e81a3d9912458ceb84649afa8df5672ca993e27c905b24e1a0d505d8d17927661a5"} 01:49:58 INFO - Running post-run listener: copy_logs_to_upload_dir 01:49:58 INFO - Copying logs to upload dir... 01:49:58 INFO - mkdir: /builds/slave/test/build/upload/logs 01:49:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.043233 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-05-17 01:49:58.197872) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 01:49:58.201279) ========= 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.QlUFACrmcn/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.4AvNYljwhd/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/069b1e88dc9ab0251c406f5c42fefe209303a2d9110e01cee1aaafb82106acbe852d26be6cfda8363c22879ffd8f8c171539538c43d0dc1cad96707a44ed5663", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d36c457346ffb6eeeed158f52df8fd2145c1079a54db87006f0ce4bf3c6b4c80bbe5bacd3b5f4ff8450e260894878e81e253bcc0fa056f3b43bc1da947b973d9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5ca60493dbc669bdb462299407ebadea07cc24018e5f6cbfc2d9c3d61a16897f29cf4e4d84f1be1965fab6482e2a939a259386294b411ad545c5dca2f206f13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f15dadf0bc575d9f3030d2781164281d82f207915e621a0055d7ad492be3e81a3d9912458ceb84649afa8df5672ca993e27c905b24e1a0d505d8d17927661a5"} build_url:https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/Hbti_7IuSY6SavlODkedSw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/069b1e88dc9ab0251c406f5c42fefe209303a2d9110e01cee1aaafb82106acbe852d26be6cfda8363c22879ffd8f8c171539538c43d0dc1cad96707a44ed5663", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d36c457346ffb6eeeed158f52df8fd2145c1079a54db87006f0ce4bf3c6b4c80bbe5bacd3b5f4ff8450e260894878e81e253bcc0fa056f3b43bc1da947b973d9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5ca60493dbc669bdb462299407ebadea07cc24018e5f6cbfc2d9c3d61a16897f29cf4e4d84f1be1965fab6482e2a939a259386294b411ad545c5dca2f206f13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f15dadf0bc575d9f3030d2781164281d82f207915e621a0055d7ad492be3e81a3d9912458ceb84649afa8df5672ca993e27c905b24e1a0d505d8d17927661a5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 01:49:58.236041) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 01:49:58.236399) ========= 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.QlUFACrmcn/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.4AvNYljwhd/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005119 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 01:49:58.285807) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 01:49:58.286153) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 01:49:58.286550) ========= ========= Total master_lag: 0.19 =========