builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0143 starttime: 1496349794.65 results: success (0) buildid: 20170601121724 builduid: 3c3259572da24a26b739a019960a817e revision: a85ff8f8399de4eea50c9f3aece921814c98d016 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:14.650062) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:14.650500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:14.650792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:14.699264) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:14.699541) ========= 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.NOVkG1keXh/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.D36R5tP2Tb/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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-06-01 13:43:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 50.7M=0s 2017-06-01 13:43:15 (50.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.479798 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:15.205003) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:15.205292) ========= 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.NOVkG1keXh/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.D36R5tP2Tb/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.029956 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:15.256614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:15.256999) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a85ff8f8399de4eea50c9f3aece921814c98d016 --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 a85ff8f8399de4eea50c9f3aece921814c98d016 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NOVkG1keXh/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.D36R5tP2Tb/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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-06-01 13:43:15,358 truncating revision to first 12 chars 2017-06-01 13:43:15,359 Setting DEBUG logging. 2017-06-01 13:43:15,359 attempt 1/10 2017-06-01 13:43:15,359 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a85ff8f8399d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 13:43:16,055 unpacking tar archive at: mozilla-beta-a85ff8f8399d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.972052 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:16.248455) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:16.248749) ========= script_repo_revision: a85ff8f8399de4eea50c9f3aece921814c98d016 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:16.249410) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:16.249696) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 13:43:16.268672) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-06-01 13:43:16.268962) ========= /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.NOVkG1keXh/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.D36R5tP2Tb/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:43:16 INFO - MultiFileLogger online at 20170601 13:43:16 in /builds/slave/test 13:43:16 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 13:43:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:43:16 INFO - {'append_to_log': False, 13:43:16 INFO - 'base_work_dir': '/builds/slave/test', 13:43:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:43:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:43:16 INFO - 'branch': 'Mozilla-Beta', 13:43:16 INFO - 'buildbot_json_path': 'buildprops.json', 13:43:16 INFO - 'config_files': ('talos/mac_config.py',), 13:43:16 INFO - 'default_actions': ('clobber', 13:43:16 INFO - 'read-buildbot-config', 13:43:16 INFO - 'download-and-extract', 13:43:16 INFO - 'populate-webroot', 13:43:16 INFO - 'create-virtualenv', 13:43:16 INFO - 'install', 13:43:16 INFO - 'run-tests'), 13:43:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:43:16 INFO - 'download_minidump_stackwalk': True, 13:43:16 INFO - 'download_symbols': 'ondemand', 13:43:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:43:16 INFO - 'tooltool.py': '/tools/tooltool.py', 13:43:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:43:16 INFO - '/tools/misc-python/virtualenv.py')}, 13:43:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:43:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:43:16 INFO - 'gecko_profile': False, 13:43:16 INFO - 'gecko_profile_interval': 0, 13:43:16 INFO - 'installer_path': 'installer.exe', 13:43:16 INFO - 'log_level': 'info', 13:43:16 INFO - 'log_name': 'talos', 13:43:16 INFO - 'log_to_console': True, 13:43:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:43:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:43:16 INFO - 'opt_config_files': (), 13:43:16 INFO - 'pip_index': False, 13:43:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:43:16 INFO - 'cmd': ('bash', 13:43:16 INFO - '-c', 13:43:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:43:16 INFO - 'enabled': True, 13:43:16 INFO - 'halt_on_failure': False, 13:43:16 INFO - 'name': 'check_screen_resolution'},), 13:43:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:43:16 INFO - 'cmd': ('bash', 13:43:16 INFO - '-c', 13:43:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:43:16 INFO - 'enabled': True, 13:43:16 INFO - 'halt_on_failure': False, 13:43:16 INFO - 'name': 'check_screen_resolution'},), 13:43:16 INFO - 'run_cmd_checks_enabled': True, 13:43:16 INFO - 'suite': 'dromaeojs-e10s', 13:43:16 INFO - 'system_bits': '32', 13:43:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:43:16 INFO - 'title': 't-yosemite-r7-0143', 13:43:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:43:16 INFO - 'use_talos_json': True, 13:43:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:43:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:43:16 INFO - 'work_dir': 'build'} 13:43:16 INFO - [mozharness: 2017-06-01 20:43:16.484026Z] Running clobber step. 13:43:16 INFO - Running pre-action listener: _resource_record_pre_action 13:43:16 INFO - Running main action method: clobber 13:43:16 INFO - rmtree: /builds/slave/test/build 13:43:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:43:18 INFO - Running post-action listener: _resource_record_post_action 13:43:18 INFO - [mozharness: 2017-06-01 20:43:18.988802Z] Finished clobber step (success) 13:43:18 INFO - [mozharness: 2017-06-01 20:43:18.988952Z] Running read-buildbot-config step. 13:43:18 INFO - Running pre-action listener: _resource_record_pre_action 13:43:18 INFO - Running main action method: read_buildbot_config 13:43:18 INFO - Using buildbot properties: 13:43:18 INFO - { 13:43:18 INFO - "project": "", 13:43:18 INFO - "product": "firefox", 13:43:18 INFO - "script_repo_revision": "production", 13:43:18 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 13:43:18 INFO - "repository": "", 13:43:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 13:43:18 INFO - "buildid": "20170601121724", 13:43:18 INFO - "pgo_build": "False", 13:43:18 INFO - "basedir": "/builds/slave/test", 13:43:18 INFO - "buildnumber": 1, 13:43:18 INFO - "slavename": "t-yosemite-r7-0143", 13:43:18 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 13:43:18 INFO - "platform": "yosemite_r7_devedition", 13:43:18 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 13:43:18 INFO - "branch": "mozilla-beta", 13:43:18 INFO - "repo_path": "releases/mozilla-beta", 13:43:18 INFO - "revision": "a85ff8f8399de4eea50c9f3aece921814c98d016", 13:43:18 INFO - "stage_platform": "macosx64-devedition", 13:43:18 INFO - "builduid": "3c3259572da24a26b739a019960a817e", 13:43:18 INFO - "slavebuilddir": "test" 13:43:18 INFO - } 13:43:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 13:43:18 INFO - Running post-action listener: _resource_record_post_action 13:43:18 INFO - [mozharness: 2017-06-01 20:43:18.992396Z] Finished read-buildbot-config step (success) 13:43:18 INFO - [mozharness: 2017-06-01 20:43:18.992536Z] Running download-and-extract step. 13:43:18 INFO - Running pre-action listener: _resource_record_pre_action 13:43:18 INFO - Running main action method: download_and_extract 13:43:18 INFO - mkdir: /builds/slave/test/build/tests 13:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:18 INFO - trying https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 13:43:18 INFO - Downloading https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/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 13:43:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/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 13:43:19 INFO - Downloaded 1287 bytes. 13:43:19 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 13:43:19 INFO - Using the following test package requirements: 13:43:19 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:43:19 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 13:43:19 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:43:19 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:43:19 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 13:43:19 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:43:19 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 13:43:19 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:43:19 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:43:19 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 13:43:19 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:43:19 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:43:19 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 13:43:19 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:43:19 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 13:43:19 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:43:19 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 13:43:19 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:43:19 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 13:43:19 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 13:43:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:43:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:43:19 INFO - Fetch https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:43:21 INFO - Expected file size: 51966273 13:43:21 INFO - Obtained file size: 51966273 13:43:38 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 13:43:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:43:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:43:38 INFO - Fetch https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:43:39 INFO - Expected file size: 51966273 13:43:39 INFO - Obtained file size: 51966273 13:43:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 13:43:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 13:43:54 INFO - Fetch https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 13:43:55 INFO - Expected file size: 14404461 13:43:55 INFO - Obtained file size: 14404461 13:43:56 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')]} 13:43:56 INFO - trying https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:43:56 INFO - Downloading https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 13:43:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/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 13:43:58 INFO - Downloaded 74894812 bytes. 13:43:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:43:58 INFO - mkdir: /builds/slave/test/properties 13:43:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:43:58 INFO - Writing to file /builds/slave/test/properties/build_url 13:43:58 INFO - Contents: 13:43:58 INFO - build_url:https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:43:59 INFO - Running post-action listener: _resource_record_post_action 13:43:59 INFO - Running post-action listener: set_extra_try_arguments 13:43:59 INFO - [mozharness: 2017-06-01 20:43:59.561120Z] Finished download-and-extract step (success) 13:43:59 INFO - [mozharness: 2017-06-01 20:43:59.561248Z] Running populate-webroot step. 13:43:59 INFO - Running pre-action listener: _resource_record_pre_action 13:43:59 INFO - Running main action method: populate_webroot 13:43:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:43:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:43:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:43:59 INFO - '--tppagecycles', 13:43:59 INFO - '7'], 13:43:59 INFO - 'tests': ['tsvgm']}}, 13:43:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:43:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:43:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:43:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:43:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:43:59 INFO - 'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:43:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:43:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:43:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:43:59 INFO - 'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['damp', 'tps']}, 13:43:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:43:59 INFO - 'tests': ['damp', 'tps']}, 13:43:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['dromaeo_dom']}, 13:43:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:43:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['basic_compositor_video']}, 13:43:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:43:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['a11yr', 13:43:59 INFO - 'ts_paint', 13:43:59 INFO - 'tpaint', 13:43:59 INFO - 'sessionrestore', 13:43:59 INFO - 'sessionrestore_no_auto_restore', 13:43:59 INFO - 'tabpaint']}, 13:43:59 INFO - 'other-e10s': {'tests': ['a11yr', 13:43:59 INFO - 'ts_paint', 13:43:59 INFO - 'tpaint', 13:43:59 INFO - 'sessionrestore', 13:43:59 INFO - 'sessionrestore_no_auto_restore', 13:43:59 INFO - 'tabpaint']}, 13:43:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:43:59 INFO - 'ts_paint', 13:43:59 INFO - 'tpaint', 13:43:59 INFO - 'sessionrestore', 13:43:59 INFO - 'sessionrestore_no_auto_restore', 13:43:59 INFO - 'tabpaint']}, 13:43:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:43:59 INFO - 'ts_paint', 13:43:59 INFO - 'tpaint', 13:43:59 INFO - 'sessionrestore', 13:43:59 INFO - 'sessionrestore_no_auto_restore', 13:43:59 INFO - 'tabpaint']}, 13:43:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['a11yr', 13:43:59 INFO - 'ts_paint', 13:43:59 INFO - 'tpaint', 13:43:59 INFO - 'sessionrestore', 13:43:59 INFO - 'sessionrestore_no_auto_restore', 13:43:59 INFO - 'tabpaint']}, 13:43:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['a11yr', 13:43:59 INFO - 'ts_paint', 13:43:59 INFO - 'tpaint', 13:43:59 INFO - 'sessionrestore', 13:43:59 INFO - 'sessionrestore_no_auto_restore', 13:43:59 INFO - 'tabpaint']}, 13:43:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['tsvgx', 13:43:59 INFO - 'tsvgr_opacity', 13:43:59 INFO - 'tart', 13:43:59 INFO - 'tscrollx', 13:43:59 INFO - 'cart', 13:43:59 INFO - 'tsvg_static']}, 13:43:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:43:59 INFO - 'tsvgr_opacity', 13:43:59 INFO - 'tart', 13:43:59 INFO - 'tscrollx', 13:43:59 INFO - 'cart', 13:43:59 INFO - 'tsvg_static']}, 13:43:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:43:59 INFO - 'talos_options': ['--disable-e10s'], 13:43:59 INFO - 'tests': ['tp5o']}, 13:43:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:43:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:43:59 INFO - 'talos_options': ['--disable-e10s', 13:43:59 INFO - '--xperf_path', 13:43:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:43:59 INFO - 'tests': ['tp5n']}, 13:43:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:43:59 INFO - 'talos_options': ['--xperf_path', 13:43:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:43:59 INFO - 'tests': ['tp5n']}}, 13:43:59 INFO - 'talos.zip': {'path': '', 13:43:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:43:59 INFO - Running post-action listener: _resource_record_post_action 13:43:59 INFO - [mozharness: 2017-06-01 20:43:59.580352Z] Finished populate-webroot step (success) 13:43:59 INFO - [mozharness: 2017-06-01 20:43:59.580483Z] Running create-virtualenv step. 13:43:59 INFO - Running pre-action listener: _resource_record_pre_action 13:43:59 INFO - Running main action method: create_virtualenv 13:43:59 INFO - Creating virtualenv /builds/slave/test/build/venv 13:43:59 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 13:43:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:43:59 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:43:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:43:59 INFO - Using real prefix '/tools/python27' 13:43:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:44:00 INFO - Installing distribute.............................................................................................................................................................................................done. 13:44:03 INFO - Installing pip.................done. 13:44:03 INFO - Return code: 0 13:44:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:44:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:44:03 INFO - Reading from file tmpfile_stdout 13:44:03 INFO - Output received: 13:44:03 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) 13:44:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:03 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:03 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 13:44:03 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 13:44:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:03 INFO - 'HOME': '/Users/cltbld', 13:44:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:03 INFO - 'LOGNAME': 'cltbld', 13:44:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:03 INFO - 'MOZ_NO_REMOTE': '1', 13:44:03 INFO - 'NO_EM_RESTART': '1', 13:44:03 INFO - 'PAGER': '/bin/cat', 13:44:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:03 INFO - 'PWD': '/builds/slave/test', 13:44:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:03 INFO - 'SHELL': '/bin/bash', 13:44:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:03 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:03 INFO - 'USER': 'cltbld', 13:44:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:03 INFO - 'XPC_FLAGS': '0x0', 13:44:03 INFO - 'XPC_SERVICE_NAME': '0', 13:44:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:03 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 13:44:03 INFO - Cleaning up... 13:44:03 INFO - Return code: 0 13:44:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:03 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:03 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 13:44:03 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 13:44:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:03 INFO - 'HOME': '/Users/cltbld', 13:44:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:03 INFO - 'LOGNAME': 'cltbld', 13:44:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:03 INFO - 'MOZ_NO_REMOTE': '1', 13:44:03 INFO - 'NO_EM_RESTART': '1', 13:44:03 INFO - 'PAGER': '/bin/cat', 13:44:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:03 INFO - 'PWD': '/builds/slave/test', 13:44:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:03 INFO - 'SHELL': '/bin/bash', 13:44:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:03 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:03 INFO - 'USER': 'cltbld', 13:44:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:03 INFO - 'XPC_FLAGS': '0x0', 13:44:03 INFO - 'XPC_SERVICE_NAME': '0', 13:44:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:04 INFO - Downloading/unpacking psutil>=3.1.1 13:44:04 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 13:44:04 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 13:44:04 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 13:44:04 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 13:44:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:44:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:44:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:44:06 INFO - Installing collected packages: psutil 13:44:06 INFO - Running setup.py install for psutil 13:44:06 INFO - building 'psutil._psutil_osx' extension 13:44:06 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 13:44:07 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 13:44:07 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 13:44:07 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 13:44:07 INFO - building 'psutil._psutil_posix' extension 13:44:07 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 13:44:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:44:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:44:07 INFO - ^ 13:44:07 INFO - 1 warning generated. 13:44:07 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 13:44:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:44:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:44:07 INFO - Successfully installed psutil 13:44:07 INFO - Cleaning up... 13:44:07 INFO - Return code: 0 13:44:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:07 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:07 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 13:44:07 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 13:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:07 INFO - 'HOME': '/Users/cltbld', 13:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:07 INFO - 'LOGNAME': 'cltbld', 13:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:07 INFO - 'MOZ_NO_REMOTE': '1', 13:44:07 INFO - 'NO_EM_RESTART': '1', 13:44:07 INFO - 'PAGER': '/bin/cat', 13:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:07 INFO - 'PWD': '/builds/slave/test', 13:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:07 INFO - 'SHELL': '/bin/bash', 13:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:07 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:07 INFO - 'USER': 'cltbld', 13:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:07 INFO - 'XPC_FLAGS': '0x0', 13:44:07 INFO - 'XPC_SERVICE_NAME': '0', 13:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:44:07 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 13:44:07 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 13:44:07 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 13:44:07 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 13:44:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:44:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:44:09 INFO - Installing collected packages: mozsystemmonitor 13:44:09 INFO - Running setup.py install for mozsystemmonitor 13:44:09 INFO - Successfully installed mozsystemmonitor 13:44:09 INFO - Cleaning up... 13:44:09 INFO - Return code: 0 13:44:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:09 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:09 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 13:44:09 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 13:44:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:09 INFO - 'HOME': '/Users/cltbld', 13:44:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:09 INFO - 'LOGNAME': 'cltbld', 13:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:09 INFO - 'MOZ_NO_REMOTE': '1', 13:44:09 INFO - 'NO_EM_RESTART': '1', 13:44:09 INFO - 'PAGER': '/bin/cat', 13:44:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:09 INFO - 'PWD': '/builds/slave/test', 13:44:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:09 INFO - 'SHELL': '/bin/bash', 13:44:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:09 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:09 INFO - 'USER': 'cltbld', 13:44:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:09 INFO - 'XPC_FLAGS': '0x0', 13:44:09 INFO - 'XPC_SERVICE_NAME': '0', 13:44:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:09 INFO - Downloading/unpacking jsonschema==2.5.1 13:44:09 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 13:44:09 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 13:44:09 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 13:44:09 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 13:44:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:44:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:44:11 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 13:44:11 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 13:44:11 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 13:44:11 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 13:44:11 INFO - Downloading functools32-3.2.3-2.tar.gz 13:44:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:44:11 INFO - warning: no files found matching '*.txt' 13:44:11 INFO - no previously-included directories found matching 'build' 13:44:11 INFO - no previously-included directories found matching 'dist' 13:44:11 INFO - no previously-included directories found matching '.git*' 13:44:11 INFO - Installing collected packages: jsonschema, functools32 13:44:11 INFO - Running setup.py install for functools32 13:44:11 INFO - warning: no files found matching '*.txt' 13:44:11 INFO - no previously-included directories found matching 'build' 13:44:11 INFO - no previously-included directories found matching 'dist' 13:44:11 INFO - no previously-included directories found matching '.git*' 13:44:11 INFO - Successfully installed jsonschema functools32 13:44:11 INFO - Cleaning up... 13:44:11 INFO - Return code: 0 13:44:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:44:11 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')]} 13:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:11 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')]} 13:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:11 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:11 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 13:44:11 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 13:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:11 INFO - 'HOME': '/Users/cltbld', 13:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:11 INFO - 'LOGNAME': 'cltbld', 13:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:11 INFO - 'MOZ_NO_REMOTE': '1', 13:44:11 INFO - 'NO_EM_RESTART': '1', 13:44:11 INFO - 'PAGER': '/bin/cat', 13:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:11 INFO - 'PWD': '/builds/slave/test', 13:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:11 INFO - 'SHELL': '/bin/bash', 13:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:11 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:11 INFO - 'USER': 'cltbld', 13:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:11 INFO - 'XPC_FLAGS': '0x0', 13:44:11 INFO - 'XPC_SERVICE_NAME': '0', 13:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:44:12 INFO - Cleaning up... 13:44:12 INFO - Return code: 0 13:44:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:44:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:44:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:44:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:12 INFO - 'HOME': '/Users/cltbld', 13:44:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:12 INFO - 'LOGNAME': 'cltbld', 13:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:12 INFO - 'MOZ_NO_REMOTE': '1', 13:44:12 INFO - 'NO_EM_RESTART': '1', 13:44:12 INFO - 'PAGER': '/bin/cat', 13:44:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:12 INFO - 'PWD': '/builds/slave/test', 13:44:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:12 INFO - 'SHELL': '/bin/bash', 13:44:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:12 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:12 INFO - 'USER': 'cltbld', 13:44:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:12 INFO - 'XPC_FLAGS': '0x0', 13:44:12 INFO - 'XPC_SERVICE_NAME': '0', 13:44:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:12 INFO - Downloading/unpacking blobuploader==1.2.4 13:44:12 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 13:44:12 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 13:44:12 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 13:44:12 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 13:44:13 INFO - Downloading blobuploader-1.2.4.tar.gz 13:44:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:44:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:44:13 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 13:44:13 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 13:44:13 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 13:44:13 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 13:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:44:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:44:14 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 13:44:14 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 13:44:14 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 13:44:14 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 13:44:14 INFO - Downloading docopt-0.6.1.tar.gz 13:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:44:14 INFO - Installing collected packages: blobuploader, requests, docopt 13:44:14 INFO - Running setup.py install for blobuploader 13:44:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:44:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:44:14 INFO - Running setup.py install for requests 13:44:15 INFO - Running setup.py install for docopt 13:44:15 INFO - Successfully installed blobuploader requests docopt 13:44:15 INFO - Cleaning up... 13:44:15 INFO - Return code: 0 13:44:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:15 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:15 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 13:44:15 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 13:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:15 INFO - 'HOME': '/Users/cltbld', 13:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:15 INFO - 'LOGNAME': 'cltbld', 13:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:15 INFO - 'MOZ_NO_REMOTE': '1', 13:44:15 INFO - 'NO_EM_RESTART': '1', 13:44:15 INFO - 'PAGER': '/bin/cat', 13:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:15 INFO - 'PWD': '/builds/slave/test', 13:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:15 INFO - 'SHELL': '/bin/bash', 13:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:15 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:15 INFO - 'USER': 'cltbld', 13:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:15 INFO - 'XPC_FLAGS': '0x0', 13:44:15 INFO - 'XPC_SERVICE_NAME': '0', 13:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:44:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ZVzBVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:44:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-HwmdRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ocq3x1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-COKH0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-euX6zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-JOuEyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-zGNd05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-elDv2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-aVlDSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ET1zCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:44:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-4Zk3TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:44:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-vtpOUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:44:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-0GJBMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:44:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-rkt2hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-llqBU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:44:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-2aWtno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:44:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-5C71qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:44:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:44:17 INFO - Running setup.py install for manifestparser 13:44:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:44:17 INFO - Running setup.py install for mozcrash 13:44:17 INFO - Running setup.py install for mozdebug 13:44:18 INFO - Running setup.py install for mozdevice 13:44:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:44:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:44:18 INFO - Running setup.py install for mozfile 13:44:18 INFO - Running setup.py install for mozhttpd 13:44:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:44:18 INFO - Running setup.py install for mozinfo 13:44:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:44:18 INFO - Running setup.py install for mozInstall 13:44:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:44:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:44:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:44:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:44:18 INFO - Running setup.py install for mozleak 13:44:18 INFO - Running setup.py install for mozlog 13:44:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:44:19 INFO - Running setup.py install for moznetwork 13:44:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:44:19 INFO - Running setup.py install for mozprocess 13:44:19 INFO - Running setup.py install for mozprofile 13:44:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:44:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:44:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:44:19 INFO - Running setup.py install for mozrunner 13:44:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:44:19 INFO - Running setup.py install for mozscreenshot 13:44:19 INFO - Running setup.py install for moztest 13:44:20 INFO - Running setup.py install for mozversion 13:44:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:44:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:44:20 INFO - Cleaning up... 13:44:20 INFO - Return code: 0 13:44:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:20 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:20 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 13:44:20 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 13:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:20 INFO - 'HOME': '/Users/cltbld', 13:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:20 INFO - 'LOGNAME': 'cltbld', 13:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:20 INFO - 'MOZ_NO_REMOTE': '1', 13:44:20 INFO - 'NO_EM_RESTART': '1', 13:44:20 INFO - 'PAGER': '/bin/cat', 13:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:20 INFO - 'PWD': '/builds/slave/test', 13:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:20 INFO - 'SHELL': '/bin/bash', 13:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:20 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:20 INFO - 'USER': 'cltbld', 13:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:20 INFO - 'XPC_FLAGS': '0x0', 13:44:20 INFO - 'XPC_SERVICE_NAME': '0', 13:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:44:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-yA5lFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:44:20 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)) 13:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:44:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-A7iNcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:44:20 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)) 13:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:44:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-igqi7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:44:20 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)) 13:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:44:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-f8M4_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:44:20 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)) 13:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:44:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-pABw5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-hJNNCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Ntrhse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-fhein4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-MU1oB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-u_fXWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-HKswcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Cfqlj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-JZamo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:44:21 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)) 13:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:44:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-19xtZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:44:22 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)) 13:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-7rfUYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:22 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)) 13:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:44:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-uQOj7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:44:22 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)) 13:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:44:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-vafkmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:44:22 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)) 13:44:22 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)) 13:44:22 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)) 13:44:22 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)) 13:44:22 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)) 13:44:22 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)) 13:44:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:23 INFO - Downloading blessings-1.6.tar.gz 13:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:44:23 INFO - Installing collected packages: blessings 13:44:23 INFO - Running setup.py install for blessings 13:44:23 INFO - Successfully installed blessings 13:44:23 INFO - Cleaning up... 13:44:24 INFO - Return code: 0 13:44:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:44:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:44:24 INFO - Reading from file tmpfile_stdout 13:44:24 INFO - Current package versions: 13:44:24 INFO - blessings == 1.6 13:44:24 INFO - blobuploader == 1.2.4 13:44:24 INFO - docopt == 0.6.1 13:44:24 INFO - functools32 == 3.2.3-2 13:44:24 INFO - jsonschema == 2.5.1 13:44:24 INFO - manifestparser == 1.1 13:44:24 INFO - mozInstall == 1.12 13:44:24 INFO - mozcrash == 1.0 13:44:24 INFO - mozdebug == 0.1 13:44:24 INFO - mozdevice == 0.49 13:44:24 INFO - mozfile == 1.2 13:44:24 INFO - mozhttpd == 0.7 13:44:24 INFO - mozinfo == 0.9 13:44:24 INFO - mozleak == 0.1 13:44:24 INFO - mozlog == 3.4 13:44:24 INFO - moznetwork == 0.27 13:44:24 INFO - mozprocess == 0.25 13:44:24 INFO - mozprofile == 0.28 13:44:24 INFO - mozrunner == 6.13 13:44:24 INFO - mozscreenshot == 0.1 13:44:24 INFO - mozsystemmonitor == 0.3 13:44:24 INFO - moztest == 0.8 13:44:24 INFO - mozversion == 1.4 13:44:24 INFO - psutil == 3.1.1 13:44:24 INFO - requests == 1.2.3 13:44:24 INFO - wsgiref == 0.1.2 13:44:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:24 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')]} 13:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:24 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')]} 13:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:24 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:24 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 13:44:24 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 13:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:24 INFO - 'HOME': '/Users/cltbld', 13:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:24 INFO - 'LOGNAME': 'cltbld', 13:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:24 INFO - 'MOZ_NO_REMOTE': '1', 13:44:24 INFO - 'NO_EM_RESTART': '1', 13:44:24 INFO - 'PAGER': '/bin/cat', 13:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:24 INFO - 'PWD': '/builds/slave/test', 13:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:24 INFO - 'SHELL': '/bin/bash', 13:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:24 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:24 INFO - 'USER': 'cltbld', 13:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:24 INFO - 'XPC_FLAGS': '0x0', 13:44:24 INFO - 'XPC_SERVICE_NAME': '0', 13:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 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)) 13:44:24 INFO - Cleaning up... 13:44:24 INFO - Return code: 0 13:44:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:44:24 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')]} 13:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:24 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')]} 13:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:24 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 0x105adfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf9daa680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, '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.NOVkG1keXh/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.D36R5tP2Tb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 13:44:24 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 13:44:24 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 13:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:24 INFO - 'HOME': '/Users/cltbld', 13:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:24 INFO - 'LOGNAME': 'cltbld', 13:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:24 INFO - 'MOZ_NO_REMOTE': '1', 13:44:24 INFO - 'NO_EM_RESTART': '1', 13:44:24 INFO - 'PAGER': '/bin/cat', 13:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:24 INFO - 'PWD': '/builds/slave/test', 13:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:24 INFO - 'SHELL': '/bin/bash', 13:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:24 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:24 INFO - 'USER': 'cltbld', 13:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:24 INFO - 'XPC_FLAGS': '0x0', 13:44:24 INFO - 'XPC_SERVICE_NAME': '0', 13:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:44:24 INFO - Cleaning up... 13:44:24 INFO - Return code: 0 13:44:24 INFO - Running post-action listener: _resource_record_post_action 13:44:24 INFO - Running post-action listener: _start_resource_monitoring 13:44:24 INFO - Starting resource monitoring. 13:44:24 INFO - [mozharness: 2017-06-01 20:44:24.894794Z] Finished create-virtualenv step (success) 13:44:24 INFO - [mozharness: 2017-06-01 20:44:24.895770Z] Running install step. 13:44:24 INFO - Running pre-action listener: _resource_record_pre_action 13:44:24 INFO - Running main action method: install 13:44:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:44:25 INFO - Reading from file tmpfile_stdout 13:44:25 INFO - Detecting whether we're running mozinstall >=1.0... 13:44:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:44:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:44:25 INFO - Reading from file tmpfile_stdout 13:44:25 INFO - Output received: 13:44:25 INFO - Usage: mozinstall [options] installer 13:44:25 INFO - Options: 13:44:25 INFO - -h, --help show this help message and exit 13:44:25 INFO - -d DEST, --destination=DEST 13:44:25 INFO - Directory to install application into. [default: 13:44:25 INFO - "/builds/slave/test"] 13:44:25 INFO - --app=APP Application being installed. [default: firefox] 13:44:25 INFO - mkdir: /builds/slave/test/build/application 13:44:25 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'] 13:44:25 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 13:44:45 INFO - Reading from file tmpfile_stdout 13:44:45 INFO - Output received: 13:44:45 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:44:45 INFO - Running post-action listener: _resource_record_post_action 13:44:45 INFO - [mozharness: 2017-06-01 20:44:45.282074Z] Finished install step (success) 13:44:45 INFO - [mozharness: 2017-06-01 20:44:45.282223Z] Running run-tests step. 13:44:45 INFO - Running pre-action listener: _resource_record_pre_action 13:44:45 INFO - Running main action method: run_tests 13:44:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:44:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:44:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:44:45 INFO - 2017-06-01 13:44:45.296 screenresolution[1627:7633] starting screenresolution argv=screenresolution get 13:44:45 INFO - 2017-06-01 13:44:45.299 screenresolution[1627:7633] Display 0: 1600x1200x32@60 13:44:45 INFO - 2017-06-01 13:44:45.305 screenresolution[1628:7635] starting screenresolution argv=screenresolution list 13:44:45 INFO - Available Modes on Display 0 13:44:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:44:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:44:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:44:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:44:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:44:45 INFO - Intel Iris: 13:44:45 INFO - Chipset Model: Intel Iris 13:44:45 INFO - Type: GPU 13:44:45 INFO - Bus: Built-In 13:44:45 INFO - VRAM (Dynamic, Max): 1536 MB 13:44:45 INFO - Vendor: Intel (0x8086) 13:44:45 INFO - Device ID: 0x0a2e 13:44:45 INFO - Revision ID: 0x0009 13:44:45 INFO - Displays: 13:44:45 INFO - Display: 13:44:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:44:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:44:45 INFO - Display Serial Number: 1600x1200 60 13:44:45 INFO - Main Display: Yes 13:44:45 INFO - Mirror: Off 13:44:45 INFO - Online: Yes 13:44:45 INFO - Rotation: Supported 13:44:45 INFO - Return code: 0 13:44:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:44:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:44:45 INFO - Python 2.7.3 13:44:45 INFO - Return code: 0 13:44:45 INFO - grabbing minidump binary from tooltool 13:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:45 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 0x1058bdd30>, '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 0x1057e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058b2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:44:45 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 13:44:45 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 13:44:45 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 13:44:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:44:45 INFO - Return code: 0 13:44:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:44:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:44:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:44:45 INFO - ENV: RUST_BACKTRACE is now 1 13:44:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:44:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:44:45 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 13:44:45 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0143 --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 13:44:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NOVkG1keXh/Render', 13:44:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:45 INFO - 'HOME': '/Users/cltbld', 13:44:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:45 INFO - 'LOGNAME': 'cltbld', 13:44:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:44:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:45 INFO - 'MOZ_NO_REMOTE': '1', 13:44:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:44:45 INFO - 'NO_EM_RESTART': '1', 13:44:45 INFO - 'PAGER': '/bin/cat', 13:44:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:45 INFO - 'PWD': '/builds/slave/test', 13:44:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:44:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:45 INFO - 'RUST_BACKTRACE': '1', 13:44:45 INFO - 'SHELL': '/bin/bash', 13:44:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D36R5tP2Tb/Listeners', 13:44:45 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 13:44:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:45 INFO - 'USER': 'cltbld', 13:44:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:45 INFO - 'XPC_FLAGS': '0x0', 13:44:45 INFO - 'XPC_SERVICE_NAME': '0', 13:44:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:45 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 13:44:45 INFO - mozversion application_buildid: 20170601121724 13:44:45 INFO - mozversion application_changeset: a85ff8f8399de4eea50c9f3aece921814c98d016 13:44:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:44:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:44:45 INFO - mozversion application_name: Firefox 13:44:45 INFO - mozversion application_remotingname: firefox-dev 13:44:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:44:45 INFO - mozversion application_vendor: Mozilla 13:44:45 INFO - mozversion application_version: 54.0 13:44:45 INFO - mozversion platform_buildid: 20170601121724 13:44:45 INFO - mozversion platform_changeset: a85ff8f8399de4eea50c9f3aece921814c98d016 13:44:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:44:45 INFO - mozversion platform_version: 54.0 13:44:45 INFO - using testdate: 1496349885 13:44:45 INFO - actual date: 1496349885 13:44:45 INFO - starting webserver on 'localhost:49220' 13:44:45 INFO - SUITE-START | Running 2 tests 13:44:45 INFO - TEST-START | dromaeo_css 13:44:45 INFO - operating with platform_type : mac_ 13:44:45 INFO - Initialising browser for dromaeo_css test... 13:44:45 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpqNLOqp/profile http://localhost:49220/getInfo.html) 13:44:48 INFO - PROCESS | 1638 | 2017-06-01 13:44:48.749 plugin-container[1641:7837] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 13:44:48 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:44:48 INFO - PROCESS | 1638 | 2017-06-01 13:44:48.749 plugin-container[1641:7837] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 13:44:48 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:44:48 INFO - PROCESS | 1638 | 2017-06-01 13:44:48.749 plugin-container[1641:7837] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:44:48 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 13:44:48 INFO - PROCESS | 1638 | colorDepth:24 13:44:48 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 13:44:48 INFO - PROCESS | 1638 | __metrics 13:44:48 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 13:44:49 INFO - TEST-INFO | 1638: exit 0 13:44:49 INFO - Browser initialized. 13:44:49 INFO - Running cycle 1/1 for dromaeo_css test... 13:44:49 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpqNLOqp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:44:51 INFO - PROCESS | 1642 | 2017-06-01 13:44:51.935 plugin-container[1643:7969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874f, name = 'com.apple.tsm.portname' 13:44:51 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:44:51 INFO - PROCESS | 1642 | 2017-06-01 13:44:51.936 plugin-container[1643:7969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 13:44:51 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:44:51 INFO - PROCESS | 1642 | 2017-06-01 13:44:51.936 plugin-container[1643:7969] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:45:01 INFO - PROCESS | 1642 | RSS: Main: 203051008 13:45:01 INFO - PROCESS | 1642 | 13:48:05 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49220/tests/dromaeo/cssquery-ext.html) 13:48:07 INFO - PROCESS | 1642 | RSS: Main: 216760320 13:48:07 INFO - PROCESS | 1642 | 13:51:09 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-ext.html (next: http://localhost:49220/tests/dromaeo/cssquery-jquery.html) 13:51:09 INFO - PROCESS | 1642 | RSS: Main: 230821888 13:51:09 INFO - PROCESS | 1642 | 13:54:11 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49220/tests/dromaeo/cssquery-mootools.html) 13:54:12 INFO - PROCESS | 1642 | RSS: Main: 232595456 13:54:12 INFO - PROCESS | 1642 | 13:57:14 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49220/tests/dromaeo/cssquery-prototype.html) 13:57:14 INFO - PROCESS | 1642 | RSS: Main: 236224512 13:57:14 INFO - PROCESS | 1642 | 14:00:17 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49220/tests/dromaeo/cssquery-yui.html) 14:00:18 INFO - PROCESS | 1642 | RSS: Main: 238473216 14:00:18 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-yui.html (next: http://localhost:49220/tests/dromaeo/cssquery-dojo.html) 14:03:21 INFO - PROCESS | 1642 | RSS: Main: 236765184 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | __start_tp_report 14:03:21 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 14:03:21 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 14:03:21 INFO - PROCESS | 1642 | |i|pagename|runs| 14:03:21 INFO - PROCESS | 1642 | |0;dojo.html;3547.840347184375;3560.359135355636;3597.8920632381023;3603.747737658365;3628.165521929956;6038.577299589025;6092.238470191226;6113.266408031037;6145.047517634763;6160.383961603849;6032.69836508175;6096.817095487131;6127.724252408641;6153.13856060153;6162.691865406723;5439.184122381655;5501.862453438656;5533.833984980457;5535.861603459905;5601.103823388259;4640.907181856361;5552.833414997556;5577.972110139443;5580.525655319303;5584.776608935637;5386.461353864602;5415.891682166354;5511.641743286694;5560.165975103726;5567.3040869891265;4567.931481027787;4582.747948862811;5548.0845660466175;5558.6942718150485;5637.210790489294;5416;5517.862053448655;5537.833864984057;5564.86087847803;5579.46995035471;5911.231539899785;6140.293866205354;6143.201383820119;6219.284782250009;6254.562180647356;6321.841953951142;6328.082428047954;6350.04749287606;6414.61512309263;6436.066770318324;6605.768798092092;6606.174228221473;6616.536842421033;6618;6625.900611490832;5917.829996522448;6268.059791031382;6268.49852011838;6282.277538083087;6315.052742088677;6401.807945761635;6482.643454610042;6571.9671401643645;6750.493712971449;6790.558613690095;6242;6315.052742088632;6401.135846660642;6413.486921046305;6420.935790642127;5753.424657534213;6493.480521558264;6564.310747371457;6585;6609.306022867626;6273.654948977759;6461.127747754087;6471.126397936218;6485.77299794505;6488.124103246096;5438.857839853596;5682.261306030189;6232.781852635227;6248.719012602405;6343.651099189589;4939.75301234944;4959.479254678206;4983.252512123138;5021.84934451967;5022.9748851256245;3794.0894185395305;4448.5773851484055;4454.309582014793;4462.241418958784;4515.729056256635;5824.446677565625;5877.177195192677;5987.161797348374;6063.54523410746;6073.878522429615;6457.3865482779065;6476.5466417350335;6505.284418713938;6537.934620653828;6568.934310656928;5284.630075894651;5727.828165155052;6112.51099912006;6126.356732543108;6133.202683651097;5113.1052065888325;5499.890002200013;5715.657060576379;5716;5725.971370143123;5657.43425657431;6600.943848984236;6631.834204144935;6735.158105236846;6745.696443660146;5960.314563825215;6365.172527571386;6439.130717353098;6547.050677651619;6625.768098116543;5889.411058894077;6223.595466294676;6331.303556608677;6704.631245281556;6806.863862722619;5347.839564813062;6273.059041143865;6308.495320374267;6367.522435817425;6425.903611445926;6558.376954189344;6564.606123632598;6566.277709451861;6570.770023049362;6577.440917522064;6031.306399764083;6132.264128304632;6387.457066149429;6388.041793730792;6433.163688720623;6181.474574661024;6253.280872699515;6265.0602409637095;6266.717997690206;6304;5493.450654934634;6232.563720539519;6313.779017734358;6328.588641738273;6341.365863413806;6267.2165979252595;6296.5504211290145;6347.143135676624;6361.363863613602;6375.458086062737;6198.473129783839;6260.843478913063;6267.185265915402;6286.402791734778;6292.11910332563 14:03:21 INFO - PROCESS | 1642 | |1;ext.html;758.3288789421363;759.6125975752365;834.1241696218968;964.4020707161561;976.8681228034213;34139.4879076814;39321.80339098304;40112.59887401125;42008.52970146038;42065;40175;40482.39276410856;40541.391879121766;40641.187176256455;40726.185476290455;8109.067457242433;8240.711575094843;10994.450277486134;11296.887031129665;11538.711532211679;11059.834102488468;11095.778084438307;11161.939615736532;11167.497462614161;11182.32906025637;11119.499622517005;11192.720181995475;11232.719182020473;11241.550337986471;11251.649802023727;11150.665480035612;11170.441477926153;11182.273152245078;11182.776344473106;11188.38463884486;9341.953290233574;10947;10969.286996345212;11024.44877756109;11078.667639970814;34883.4767478488;35148.472772908426;35259;35287;35292.76475323351;3817.9618203818163;3846.2884366392027;3883.7475564088527;3889.1638297765835;3948.854832098688;20654;21163.04766285521;25935.870320648464;27320.316992075357;27592.448151036868;2752.692471076243;3152.7477801775804;3159.210197450637;3181.4114388837907;3197.7441804655573;294715.99999999785;325244.49511009885;330195;383116;385918;43874.78062609667;44086.77956610196;44148.67553973386;44233;44354.77822610931;38696;45862.31206531905;46124.8468788283;46183;46296.07407851891;13851.653708657162;13991.510297139552;14025.859741402659;14039.859601403856;14115.153090814583;12118;12241.265524068584;12277.447514861853;12302.876971230173;12327.137100403064;11754.11844111695;11807.586734464252;11810.232334898204;11884.76230475403;11938.22401543897;5659.801906933238;5705.058665320175;5719.942800572025;5732.16883551891;5745.3105627324985;1089.3300620118728;1092.9780655087518;1095.7370231144469;1114.147677027075;1233.5374234662002;29211.415771684446;29749.256268593457;29954.55068173979;29962.85018574937;30535.541966870514;1065.4033741105006;1070.9518071686637;1101.1136035491347;1116.6984914073155;1117.6423544465856;10824.203322976866;13350.933245333712;13834.031617786659;14020.439182432588;14038;498560;502591.97408026183;503347;503726.48136759084;504264;47394.289085663746;47510.5248947513;47579.5242047582;47725.5227447728;48032.5196748035;1399.6640806206437;1425.4369524037947;1433.025542631;1437.3531910640254;1440.827100747917;20518.89740551288;25664.973401063748;26248.475030499663;26480.867595661897;26722;40949.795251023555;41287.793561032006;41338;41386.79306603448;41395.965100872716;484688.57655712904;486225;486766;487124;487276;29183.708162918098;29495.55756663609;30873.536896946116;31204.063878083693;32516.861909833042;39451.01372465711;39659.40510892281;39912.401313980896;40475.19049618932;40615.3907691379;22270.665940011237;28258.85870570634;29453;29726.25684357822;29825.403491930472;28963.565546516398;29132.417351653272;29818.552721709624;29837.70162298436;30094;33654.49518257277;34425.65574344224;34809.47785783165;35074.29851403009;35165.64834351726;28184.872605095567;29097.272568185967;29635.703642963294;30023.3995320088;30091.097267082023;20400.59198816045;23853.926573303896;24044.15845445401;24297.878510607334;24361.025558977442 14:03:21 INFO - PROCESS | 1642 | |2;jquery.html;362.360319554018;403.1252182763404;408.59957241902913;412.36289932054973;454.06915822563735;20496.385108446753;20729.85985770782;21290.680639790415;21722.565548689017;21728.239511617096;21494.785052149473;21643.567128657418;21767.3469795906;22350.21774237902;22593.983270752815;6707.1616047994;6987.89518157228;6994.230634630187;7046.788596342092;7059.08231929851;6872.518923675345;7073;7413.740519081834;7545.245475452438;7559.9416081748595;7311.341979221869;7509.436792240576;7514.39884809214;7521.398288136936;7532.435067369942;7287.34413902746;7589.620518974057;7637.236276372374;7670.0795904491815;7677.193894641066;6115.602485838451;6497.837554061139;7294.744683936036;7364.521306115139;7386.409087273005;23687.526249475086;24307.513849722905;25635.871820640965;25979;26210.213693589223;2494.8128890333114;2922.941541169186;2993.4162838246552;3009.6388433387915;3019.562163486304;15508;15575.37698492059;15662.9216853915;15672.843271567366;16026;2053.3224036068063;2112.4613223627875;2113.936581902545;2116.7354080739906;2121.427214652035;101544.79711731219;107967;139171.30414347965;142958;144066;20378.69431958522;21055.157793688388;21142.365729028155;21337.573248534944;21342.35972920815;24850.50298994046;30808.075757727303;31245.375092498023;31319;31328.686713132578;8447.619857106445;10262.025107614765;10304.021117993778;10478.528466218886;10643.893561064291;8124.821900824466;8212.876806847902;8715.651373945098;8731.126887311219;8912.8663070054;8633.481991080555;8825.249853762374;9501.667441639647;9521.047895210562;9682.225421966226;3573.9285214296087;3912.9043867717087;3955.861544845917;4153.68847336451;4336.34954756789;906.1210625693067;1029.9279050466546;1032.7108409645311;1038.2420832792104;1052.742078190833;17404.82595174032;18194.18126184325;18436.801607895442;20428.897855510626;20629.277975270794;891.4695756025154;1026.973026973027;1028.7222449938624;1055.6885718712997;1060.316096118015;10399.84400233997;10744.194185436123;10857.619983300547;10893.07408870255;10898.07366373867;603198;732822;735313;741772;759329;122568;127352.36323818321;133497.33251333874;137636;149155;1227.1839226914003;1232.6302109367155;1252.8997680185564;1255.9686007849878;1261.4008346035744;14834.851651483346;17172;17355.045472499132;17762.822371776634;17985.10074496296;58071.83856322934;59300.70349648224;59569.404305956385;60106;60157.699211505416;76408.61795690986;81576.59211703904;83167.16832831837;84213;85534.7169792466;9280.72157835266;9942.669205816133;11481.425928703364;11528;11830.46762895689;12485.87514124847;12641.557545485864;12686.936565317113;12721.8091728626;12734.74530509366;9535.809283814424;10685.893141068489;11012;11054.889451105708;11108.166887483309;10312.845307320245;10435.530401132119;10708.625198118028;10744.355338679705;10747.247692661438;11418.429078545872;11514;11533.1926765129;11807.76384472323;12001.81997270059;9000.954995225244;11321.150913681342;11466.197366184288;11528.308301501937;11825.645230643095;10808.513616887101;10929.83605245906;10933.781324373627;10967.122630189726;11347.54609815598 14:03:21 INFO - PROCESS | 1642 | |3;mootools.html;1427.1437137717367;1547.1490680125928;1601.9507222769091;1611.234663534821;1612.5081850035742;9375.296852736037;9586.281028922833;9691.370060946034;9750.756231094227;11155.665330040112;10684.358938463678;11039.448027598628;11046.944765276183;11059.944700276508;11074.058705010084;2010.5576773109892;2357.316378250305;2405.1101092595677;2413.1433341163897;2414.4567472318677;2365.8580485170946;2393.3537944754908;2397.844140130886;2407.843490173142;2410.7348191698898;2370.905163793455;2383.5828729972272;2390.0320370249988;2391.1989483522952;2395.556821987929;1928.469670840516;2324.4537533679572;2341.274204996457;2342.61346877765;2344.2732752846673;2009.9196032158695;2357.7877990980724;2360.221127028077;2367.2306500387444;2368.052778888441;9188.586513606837;9435.528223588793;11218.663440096729;11246.775064498746;11258.887411125866;5698.116791897262;5850.0932355484965;5884.176215329857;5886.499647529965;5890.970545147246;2938.2213713365977;3499.177693242086;3695.0762309422644;3719.088823238324;3721.79530125843;1157.4212893553224;1229.643403413009;1230.2065167966612;1234.2841152131741;1268.6384380451527;15376.154311512973;15398.769018464733;15418.997765145341;15451.768223476545;15480.922595387063;4720.740359280204;4892.143874821963;4900.093482705711;4979.526944940225;5007.298978143063;12947.611571652866;12959.870401296055;12963.22220666763;12964.351782410842;12978.545750898673;5756.942430575725;5775.047117225695;5830.5627077968475;5983.252093488314;5983.880322393527;4426.64586833059;4906.730129842893;5116.769745361467;5191.402988656278;5246.632735708464;4001.0597509585223;4098.913787846223;4123.649489793401;4173.812178451977;4195.91608167835;1104.2877344113;1107.0755918807886;1112.7941330853819;1122.7922834275685;1124.516457923101;944.6599224279254;966.1884017425397;971.1308379000754;977.9070988256104;1007.2596641468662;6560;6640.335966403321;6652.101966234496;6855.040294358794;6889.827754306082;985.3053597213947;1005.3565717940523;1017.811704834608;1024.7438140464883;1041.57295508841;3539.823008849599;4226.4505614270565;4274.722143060691;4342.240107981153;4347.500037495728;9581.616735330648;12323.938380308042;12332.50669973191;12386.08015757559;12401.875981240253;11875.940620297017;12050.156489045683;12127.514899403925;12287.324197169242;12315.13794034427;9249.768755781159;9251.398659087003;9333;9364.812703746024;9393;5370.785168593213;5679.176519404746;6230.813075607739;6263.185785847811;6275.3724627536885;10850.023497885228;10965.451727413756;10969.067629251604;11093.889061109285;11172.106231501622;11137.331760094421;11214.215004949576;11385.544578216779;11426.25729327591;11619.186656933933;7398.815029624302;8079.919200808152;8339.041010283894;8370.497770133812;8569.400141990001;10171.626830377805;10496.475176241005;10538.683839484827;10546.419946902994;10561.735956601147;6681.799546013627;8060.193980601893;8825.528172533071;8834.425762325429;8863.778405539655;7176.569405835667;7389.261073892568;8700.782480437787;8769.254613357936;8799.032106468412;7770.222977702185;7804.0635123785505;7857.764267071997;7861.724839630586;7875.330596899328;5805.274340707411;5818.883622327615;5833.212516310244;5993.340732519332;6069.514438844793;5938.020226662552;6074.817755467343;6108.908366374419;6138.508919286357;6189.35011823752 14:03:21 INFO - PROCESS | 1642 | |4;prototype.html;372.7614326830829;386.7099675243567;388.4910766895367;393.0586245940973;397.65404098434357;4882.316475693406;4951.93533390321;5475.151351540512;5494.263900547626;5532.944670553298;4809.783559739812;5112.514311140445;5244.501772331632;5297.311349524556;5300.886813769103;3612.4039533476944;4219.278565325371;4305.504866940295;4417.845375411861;4429.579189976947;3554.4312909934415;3605.36906041443;4267.818659529023;4326.199653064194;4342.348647702838;3902.570717221103;4301.8064187111495;4326.286162783137;4334.328179132223;4357.891052723691;3693.722970777189;4333.359977618816;4375.431193944799;4382.386465894777;4417.293233082723;3359.445691460906;3490.359531020442;3783.6405541473514;4021.577734337911;4042.9797851010558;4165.812538435777;4372.950491881926;5131.820386286462;5181.5077567631015;5190.195519694454;4132.524759652649;4195.370694395834;4894.136929896015;5139.428097472258;5199.766010529535;5298.317669219894;5384.892302153974;5713.628614140109;5730.856728581777;5747;3561.4863682934647;3973.443717879499;4209.74741515507;4214.978925105385;4541.772911354419;5288.074586947308;5705.837695289078;5927.229460170193;6185.721642526097;6191.535634827361;3988.8205030773684;4381.496127945327;4674.626029917598;4689.459458109174;4737.763111844394;4287.399764033038;4763.642726795435;4772;4777.761111944389;4789.976050119727;4788.880277993078;4911.705297682151;4995.325631039763;5001.374278503815;5133.432264576084;4486.349479325545;4714.504976977437;5074.923876141861;5214.765335559909;5331.626786125012;1483.5845963130341;1508.3363320139104;1576.085870195284;1585.7304258276122;1625.9756103658456;988.6539711101201;1217.3565686323393;1219.3720234079456;1234.456838990841;1241.8698983924767;4540.54810402379;4607.58531732139;4738.905221895543;5171.870703232449;5171.974140129275;4677.3919390479705;5542.251796007568;5573.972130139405;5751.971240143856;5892.882142357129;4614.353990441341;4783.6605750389945;5030.0694371541385;5087.3386459759995;5133.6385262778795;2456.606942889129;2920.956185657217;2957.6746557878614;3049.2798785481677;3092.226943264184;5321.255024296602;5432.777625034336;5914.792982245687;6057.759098882077;6132.724027418777;4936.851894443173;5214.97392513035;5583.497485226349;5696.715164241854;6102;5239.952009598096;5523.6685798852195;5819.098039803837;6188.019833653188;6229.875402491834;4920.769807548113;5239.738013099406;5282.709450980233;5888.705564721832;5939.524838013035;5438.809641662523;5591.440855914376;5785.710714464176;5841.744025034638;5873.2658417697785;5625.774969001194;5803.448672376117;5929.881402371842;6023.728932197974;6227.937720622917;3986.5614782373336;4168.958310416979;4269.893252668709;4462.933056004098;4631.073785242897;5031.320771695774;5168.508991645787;5417;5645.983722929748;5842.211301474246;4916.361892199675;5259.4477579853565;5486;5605.82277721683;5688.886222275614;5193.714345711022;5359.812406565751;5364.222187782828;5732.426757324234;5751.424857514216;4685.180093483695;5256.421793602777;5282.339707536558;5529.686844261482;5567.944320556742;4855.295982082649;5052.823151189691;5149.56228720548;5643.717814109196;5739;4731.6214702823;5359.839204823861;5380.892382152257;5453.7818487260065;5635.380108188014 14:03:21 INFO - PROCESS | 1642 | |5;yui.html;712.2450202785047;726.614894106124;733.3106879533238;801.0427539090786;863.7149740585605;15905.363785448582;16537.7596680249;16823.91588042058;17010.744838827413;17198.570035749082;15310.234488275597;15650.139242341667;15674;15836.604084897883;15864.841351586481;959.4051687953461;986.8914419413894;987.0770829274616;987.6345752071708;988.4415305352488;953.9427634341927;979.2166266986418;983.2281658897766;987.8221920054388;995.1839491616877;1026.563710423071;1027.4349107990613;1028.300755486269;1028.6296933104077;1030.9226807989394;1023.5189460953377;1034.3018462537796;1034.591336422109;1035.7410647338165;1035.7824856780085;772.3203580848817;774.2335088262636;774.767569729079;775.1241097559778;776.8368642585064;15437.768433473508;16362.445567671051;16609.6678066438;16932.492025239262;16979;14245.715085698332;14447.277636118151;14511.274436278143;14535.78196327056;14544.927275363556;11687.474063667156;11735;11816.763664726743;11860.288382697066;12429.31638759868;3639.162992511711;3809.847606095753;3839.2897313997;3926.587708290617;3935.8032098394965;109696.4515177427;116129;116434;117780;117809.41095294555;11112.666620001413;11132.10943124548;11160.497777599865;11326.980571748418;11433.942830285961;18741.437756867315;23231.88384058069;23413.946372413284;23482.765172348398;23561.29316120519;7996.600169991477;8005.519668819889;8072.475289106189;8126.431149819456;8270.751877443687;4115.218108559364;4230.386593943923;4294.334378171388;4467.441569803775;4552.226121559343;3405.3019131078067;3469.358168738741;3493.458513930345;3498.422760244531;3501.842417091237;619.454879705856;627.7300760672955;642.3351830855042;643.8519140597689;651.3258777165611;632.5287660692721;647.7441410642864;664.9468042556584;673.5588189735731;683.8803209438329;8882.378233523721;8891;9877.308588398886;10045.29682922203;10194.949025254975;647.9222493300834;667.452688795193;670.7356632747307;674.308833445722;691.7094820175538;4597.586764522951;4852.733099679481;4873.463918968911;4904.313396124546;4914.36113305268;80561.19438805654;91770.08229917749;99010;99274;99869;78609.60695196487;81756.18243817605;84816;85787.57106214554;86885.565572173;75547;77539;77999.61000194962;79301.60349198217;80383;8953.418027828171;9203.723888283363;9394.483303418378;9478.336516443782;9513.857292140485;46694;51278;51716.22425663693;51956.70108247324;52745.68135796636;47860.760696196296;62040;66814;66842.99735503874;69319;12761.809547613097;13042.347882606022;13144.474221031052;13618.114822536503;13842.169469831842;13434.193948363129;14016.528264532077;14888.776668349767;15174.848251517344;15400.614984625474;13707.451701931812;13767.629615268188;13845.515406960709;14016.509422170582;14197.077189982618;13837.446502139801;13882;13927.442902283796;14034.649133771738;14528.709425811214;13043.673908152372;13551;13739.313034348042;13748.862511375159;13855.930720346334;9829.754256143653;9869.210463163074;10040.196784257096;10076.647317343857;10603.204759643211;10831.2959657622;11291.322520648788;11343.035841953526;11567.421628918688;11572.59495917639 14:03:21 INFO - PROCESS | 1642 | __end_tp_report 14:03:21 INFO - PROCESS | 1642 | __start_cc_report 14:03:21 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,374 14:03:21 INFO - PROCESS | 1642 | __end_cc_report 14:03:21 INFO - PROCESS | 1642 | __startTimestamp1496351001438__endTimestamp 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | ------- Summary: start ------- 14:03:21 INFO - PROCESS | 1642 | Number of tests: 6 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5953.86 Median:6223.60 stddev:699.21 (11.2%) stddev-sans-first:675.39 14:03:21 INFO - PROCESS | 1642 | Values: 3547.8 3560.4 3597.9 3603.7 3628.2 6038.6 6092.2 6113.3 6145.0 6160.4 6032.7 6096.8 6127.7 6153.1 6162.7 5439.2 5501.9 5533.8 5535.9 5601.1 4640.9 5552.8 5578.0 5580.5 5584.8 5386.5 5415.9 5511.6 5560.2 5567.3 4567.9 4582.7 5548.1 5558.7 5637.2 5416.0 5517.9 5537.8 5564.9 5579.5 5911.2 6140.3 6143.2 6219.3 6254.6 6321.8 6328.1 6350.0 6414.6 6436.1 6605.8 6606.2 6616.5 6618.0 6625.9 5917.8 6268.1 6268.5 6282.3 6315.1 6401.8 6482.6 6572.0 6750.5 6790.6 6242.0 6315.1 6401.1 6413.5 6420.9 5753.4 6493.5 6564.3 6585.0 6609.3 6273.7 6461.1 6471.1 6485.8 6488.1 5438.9 5682.3 6232.8 6248.7 6343.7 4939.8 4959.5 4983.3 5021.8 5023.0 3794.1 4448.6 4454.3 4462.2 4515.7 5824.4 5877.2 5987.2 6063.5 6073.9 6457.4 6476.5 6505.3 6537.9 6568.9 5284.6 5727.8 6112.5 6126.4 6133.2 5113.1 5499.9 5715.7 5716.0 5726.0 5657.4 6600.9 6631.8 6735.2 6745.7 5960.3 6365.2 6439.1 6547.1 6625.8 5889.4 6223.6 6331.3 6704.6 6806.9 5347.8 6273.1 6308.5 6367.5 6425.9 6558.4 6564.6 6566.3 6570.8 6577.4 6031.3 6132.3 6387.5 6388.0 6433.2 6181.5 6253.3 6265.1 6266.7 6304.0 5493.5 6232.6 6313.8 6328.6 6341.4 6267.2 6296.6 6347.1 6361.4 6375.5 6198.5 6260.8 6267.2 6286.4 6292.1 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:56763.88 Median:29958.70 stddev:119967.17 (400.4%) stddev-sans-first:120230.01 14:03:21 INFO - PROCESS | 1642 | Values: 758.3 759.6 834.1 964.4 976.9 34139.5 39321.8 40112.6 42008.5 42065.0 40175.0 40482.4 40541.4 40641.2 40726.2 8109.1 8240.7 10994.5 11296.9 11538.7 11059.8 11095.8 11161.9 11167.5 11182.3 11119.5 11192.7 11232.7 11241.6 11251.6 11150.7 11170.4 11182.3 11182.8 11188.4 9342.0 10947.0 10969.3 11024.4 11078.7 34883.5 35148.5 35259.0 35287.0 35292.8 3818.0 3846.3 3883.7 3889.2 3948.9 20654.0 21163.0 25935.9 27320.3 27592.4 2752.7 3152.7 3159.2 3181.4 3197.7 294716.0 325244.5 330195.0 383116.0 385918.0 43874.8 44086.8 44148.7 44233.0 44354.8 38696.0 45862.3 46124.8 46183.0 46296.1 13851.7 13991.5 14025.9 14039.9 14115.2 12118.0 12241.3 12277.4 12302.9 12327.1 11754.1 11807.6 11810.2 11884.8 11938.2 5659.8 5705.1 5719.9 5732.2 5745.3 1089.3 1093.0 1095.7 1114.1 1233.5 29211.4 29749.3 29954.6 29962.9 30535.5 1065.4 1071.0 1101.1 1116.7 1117.6 10824.2 13350.9 13834.0 14020.4 14038.0 498560.0 502592.0 503347.0 503726.5 504264.0 47394.3 47510.5 47579.5 47725.5 48032.5 1399.7 1425.4 1433.0 1437.4 1440.8 20518.9 25665.0 26248.5 26480.9 26722.0 40949.8 41287.8 41338.0 41386.8 41396.0 484688.6 486225.0 486766.0 487124.0 487276.0 29183.7 29495.6 30873.5 31204.1 32516.9 39451.0 39659.4 39912.4 40475.2 40615.4 22270.7 28258.9 29453.0 29726.3 29825.4 28963.6 29132.4 29818.6 29837.7 30094.0 33654.5 34425.7 34809.5 35074.3 35165.6 28184.9 29097.3 29635.7 30023.4 30091.1 20400.6 23853.9 24044.2 24297.9 24361.0 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:40393.76 Median:21418.57 stddev:118787.48 (554.6%) stddev-sans-first:119082.68 14:03:21 INFO - PROCESS | 1642 | Values: 362.4 403.1 408.6 412.4 454.1 20496.4 20729.9 21290.7 21722.6 21728.2 21494.8 21643.6 21767.3 22350.2 22594.0 6707.2 6987.9 6994.2 7046.8 7059.1 6872.5 7073.0 7413.7 7545.2 7559.9 7311.3 7509.4 7514.4 7521.4 7532.4 7287.3 7589.6 7637.2 7670.1 7677.2 6115.6 6497.8 7294.7 7364.5 7386.4 23687.5 24307.5 25635.9 25979.0 26210.2 2494.8 2922.9 2993.4 3009.6 3019.6 15508.0 15575.4 15662.9 15672.8 16026.0 2053.3 2112.5 2113.9 2116.7 2121.4 101544.8 107967.0 139171.3 142958.0 144066.0 20378.7 21055.2 21142.4 21337.6 21342.4 24850.5 30808.1 31245.4 31319.0 31328.7 8447.6 10262.0 10304.0 10478.5 10643.9 8124.8 8212.9 8715.7 8731.1 8912.9 8633.5 8825.2 9501.7 9521.0 9682.2 3573.9 3912.9 3955.9 4153.7 4336.3 906.1 1029.9 1032.7 1038.2 1052.7 17404.8 18194.2 18436.8 20428.9 20629.3 891.5 1027.0 1028.7 1055.7 1060.3 10399.8 10744.2 10857.6 10893.1 10898.1 603198.0 732822.0 735313.0 741772.0 759329.0 122568.0 127352.4 133497.3 137636.0 149155.0 1227.2 1232.6 1252.9 1256.0 1261.4 14834.9 17172.0 17355.0 17762.8 17985.1 58071.8 59300.7 59569.4 60106.0 60157.7 76408.6 81576.6 83167.2 84213.0 85534.7 9280.7 9942.7 11481.4 11528.0 11830.5 12485.9 12641.6 12686.9 12721.8 12734.7 9535.8 10685.9 11012.0 11054.9 11108.2 10312.8 10435.5 10708.6 10744.4 10747.2 11418.4 11514.0 11533.2 11807.8 12001.8 9001.0 11321.2 11466.2 11528.3 11825.6 10808.5 10929.8 10933.8 10967.1 11347.5 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6494.99 Median:3617.45 stddev:3979.12 (110.0%) stddev-sans-first:3972.06 14:03:21 INFO - PROCESS | 1642 | Values: 1427.1 1547.1 1602.0 1611.2 1612.5 9375.3 9586.3 9691.4 9750.8 11155.7 10684.4 11039.4 11046.9 11059.9 11074.1 2010.6 2357.3 2405.1 2413.1 2414.5 2365.9 2393.4 2397.8 2407.8 2410.7 2370.9 2383.6 2390.0 2391.2 2395.6 1928.5 2324.5 2341.3 2342.6 2344.3 2009.9 2357.8 2360.2 2367.2 2368.1 9188.6 9435.5 11218.7 11246.8 11258.9 5698.1 5850.1 5884.2 5886.5 5891.0 2938.2 3499.2 3695.1 3719.1 3721.8 1157.4 1229.6 1230.2 1234.3 1268.6 15376.2 15398.8 15419.0 15451.8 15480.9 4720.7 4892.1 4900.1 4979.5 5007.3 12947.6 12959.9 12963.2 12964.4 12978.5 5756.9 5775.0 5830.6 5983.3 5983.9 4426.6 4906.7 5116.8 5191.4 5246.6 4001.1 4098.9 4123.6 4173.8 4195.9 1104.3 1107.1 1112.8 1122.8 1124.5 944.7 966.2 971.1 977.9 1007.3 6560.0 6640.3 6652.1 6855.0 6889.8 985.3 1005.4 1017.8 1024.7 1041.6 3539.8 4226.5 4274.7 4342.2 4347.5 9581.6 12323.9 12332.5 12386.1 12401.9 11875.9 12050.2 12127.5 12287.3 12315.1 9249.8 9251.4 9333.0 9364.8 9393.0 5370.8 5679.2 6230.8 6263.2 6275.4 10850.0 10965.5 10969.1 11093.9 11172.1 11137.3 11214.2 11385.5 11426.3 11619.2 7398.8 8079.9 8339.0 8370.5 8569.4 10171.6 10496.5 10538.7 10546.4 10561.7 6681.8 8060.2 8825.5 8834.4 8863.8 7176.6 7389.3 8700.8 8769.3 8799.0 7770.2 7804.1 7857.8 7861.7 7875.3 5805.3 5818.9 5833.2 5993.3 6069.5 5938.0 6074.8 6108.9 6138.5 6189.4 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4636.82 Median:5030.70 stddev:1313.96 (26.1%) stddev-sans-first:1278.07 14:03:21 INFO - PROCESS | 1642 | Values: 372.8 386.7 388.5 393.1 397.7 4882.3 4951.9 5475.2 5494.3 5532.9 4809.8 5112.5 5244.5 5297.3 5300.9 3612.4 4219.3 4305.5 4417.8 4429.6 3554.4 3605.4 4267.8 4326.2 4342.3 3902.6 4301.8 4326.3 4334.3 4357.9 3693.7 4333.4 4375.4 4382.4 4417.3 3359.4 3490.4 3783.6 4021.6 4043.0 4165.8 4373.0 5131.8 5181.5 5190.2 4132.5 4195.4 4894.1 5139.4 5199.8 5298.3 5384.9 5713.6 5730.9 5747.0 3561.5 3973.4 4209.7 4215.0 4541.8 5288.1 5705.8 5927.2 6185.7 6191.5 3988.8 4381.5 4674.6 4689.5 4737.8 4287.4 4763.6 4772.0 4777.8 4790.0 4788.9 4911.7 4995.3 5001.4 5133.4 4486.3 4714.5 5074.9 5214.8 5331.6 1483.6 1508.3 1576.1 1585.7 1626.0 988.7 1217.4 1219.4 1234.5 1241.9 4540.5 4607.6 4738.9 5171.9 5172.0 4677.4 5542.3 5574.0 5752.0 5892.9 4614.4 4783.7 5030.1 5087.3 5133.6 2456.6 2921.0 2957.7 3049.3 3092.2 5321.3 5432.8 5914.8 6057.8 6132.7 4936.9 5215.0 5583.5 5696.7 6102.0 5240.0 5523.7 5819.1 6188.0 6229.9 4920.8 5239.7 5282.7 5888.7 5939.5 5438.8 5591.4 5785.7 5841.7 5873.3 5625.8 5803.4 5929.9 6023.7 6227.9 3986.6 4169.0 4269.9 4462.9 4631.1 5031.3 5168.5 5417.0 5646.0 5842.2 4916.4 5259.4 5486.0 5605.8 5688.9 5193.7 5359.8 5364.2 5732.4 5751.4 4685.2 5256.4 5282.3 5529.7 5567.9 4855.3 5052.8 5149.6 5643.7 5739.0 4731.6 5359.8 5380.9 5453.8 5635.4 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20431.64 Median:3724.51 stddev:29051.75 (780.0%) stddev-sans-first:29095.52 14:03:21 INFO - PROCESS | 1642 | Values: 712.2 726.6 733.3 801.0 863.7 15905.4 16537.8 16823.9 17010.7 17198.6 15310.2 15650.1 15674.0 15836.6 15864.8 959.4 986.9 987.1 987.6 988.4 953.9 979.2 983.2 987.8 995.2 1026.6 1027.4 1028.3 1028.6 1030.9 1023.5 1034.3 1034.6 1035.7 1035.8 772.3 774.2 774.8 775.1 776.8 15437.8 16362.4 16609.7 16932.5 16979.0 14245.7 14447.3 14511.3 14535.8 14544.9 11687.5 11735.0 11816.8 11860.3 12429.3 3639.2 3809.8 3839.3 3926.6 3935.8 109696.5 116129.0 116434.0 117780.0 117809.4 11112.7 11132.1 11160.5 11327.0 11433.9 18741.4 23231.9 23413.9 23482.8 23561.3 7996.6 8005.5 8072.5 8126.4 8270.8 4115.2 4230.4 4294.3 4467.4 4552.2 3405.3 3469.4 3493.5 3498.4 3501.8 619.5 627.7 642.3 643.9 651.3 632.5 647.7 664.9 673.6 683.9 8882.4 8891.0 9877.3 10045.3 10194.9 647.9 667.5 670.7 674.3 691.7 4597.6 4852.7 4873.5 4904.3 4914.4 80561.2 91770.1 99010.0 99274.0 99869.0 78609.6 81756.2 84816.0 85787.6 86885.6 75547.0 77539.0 77999.6 79301.6 80383.0 8953.4 9203.7 9394.5 9478.3 9513.9 46694.0 51278.0 51716.2 51956.7 52745.7 47860.8 62040.0 66814.0 66843.0 69319.0 12761.8 13042.3 13144.5 13618.1 13842.2 13434.2 14016.5 14888.8 15174.8 15400.6 13707.5 13767.6 13845.5 14016.5 14197.1 13837.4 13882.0 13927.4 14034.6 14528.7 13043.7 13551.0 13739.3 13748.9 13855.9 9829.8 9869.2 10040.2 10076.6 10603.2 10831.3 11291.3 11343.0 11567.4 11572.6 14:03:21 INFO - PROCESS | 1642 | -------- Summary: end -------- 14:03:21 INFO - PROCESS | 1642 | 14:03:21 INFO - TEST-INFO | 1642: exit 0 14:03:21 INFO - TEST-OK | dromaeo_css | took 1115981ms 14:03:21 INFO - TEST-START | kraken 14:03:21 INFO - operating with platform_type : mac_ 14:03:21 INFO - Initialising browser for kraken test... 14:03:21 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpjSfRUY/profile http://localhost:49220/getInfo.html) 14:03:24 INFO - PROCESS | 1649 | 2017-06-01 14:03:24.501 plugin-container[1651:10169] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453b, name = 'com.apple.tsm.portname' 14:03:24 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:03:24 INFO - PROCESS | 1649 | 2017-06-01 14:03:24.502 plugin-container[1651:10169] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 14:03:24 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:03:24 INFO - PROCESS | 1649 | 2017-06-01 14:03:24.502 plugin-container[1651:10169] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:03:24 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 14:03:24 INFO - PROCESS | 1649 | colorDepth:24 14:03:24 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 14:03:24 INFO - PROCESS | 1649 | __metrics 14:03:24 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 14:03:25 INFO - TEST-INFO | 1649: exit 0 14:03:25 INFO - Browser initialized. 14:03:25 INFO - Running cycle 1/1 for kraken test... 14:03:25 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpjSfRUY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:03:27 INFO - PROCESS | 1652 | 2017-06-01 14:03:27.538 plugin-container[1653:10296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 14:03:27 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:03:27 INFO - PROCESS | 1652 | 2017-06-01 14:03:27.538 plugin-container[1653:10296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 14:03:27 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:03:27 INFO - PROCESS | 1652 | 2017-06-01 14:03:27.538 plugin-container[1653:10296] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:03:37 INFO - PROCESS | 1652 | RSS: Main: 203329536 14:03:37 INFO - PROCESS | 1652 | 14:03:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection) 14:03:41 INFO - PROCESS | 1652 | RSS: Main: 216604672 14:03:41 INFO - PROCESS | 1652 | 14:03:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-dft) 14:03:44 INFO - PROCESS | 1652 | RSS: Main: 224698368 14:03:44 INFO - PROCESS | 1652 | 14:03:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-fft) 14:03:48 INFO - PROCESS | 1652 | RSS: Main: 228257792 14:03:48 INFO - PROCESS | 1652 | 14:03:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator) 14:03:51 INFO - PROCESS | 1652 | RSS: Main: 228569088 14:03:51 INFO - PROCESS | 1652 | 14:03:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:03:53 INFO - PROCESS | 1652 | RSS: Main: 229937152 14:03:53 INFO - PROCESS | 1652 | 14:03:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom) 14:03:57 INFO - PROCESS | 1652 | RSS: Main: 233865216 14:03:57 INFO - PROCESS | 1652 | 14:04:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate) 14:04:01 INFO - PROCESS | 1652 | RSS: Main: 233381888 14:04:01 INFO - PROCESS | 1652 | 14:04:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial) 14:04:04 INFO - PROCESS | 1652 | RSS: Main: 233541632 14:04:04 INFO - PROCESS | 1652 | 14:04:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:04:07 INFO - PROCESS | 1652 | RSS: Main: 233226240 14:04:07 INFO - PROCESS | 1652 | 14:04:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:04:09 INFO - PROCESS | 1652 | RSS: Main: 233209856 14:04:09 INFO - PROCESS | 1652 | 14:04:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:04:12 INFO - PROCESS | 1652 | RSS: Main: 233238528 14:04:12 INFO - PROCESS | 1652 | 14:04:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:04:16 INFO - PROCESS | 1652 | RSS: Main: 233639936 14:04:16 INFO - PROCESS | 1652 | 14:04:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:04:19 INFO - PROCESS | 1652 | RSS: Main: 233361408 14:04:19 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49220/tests/kraken/driver.html?testName=ai-astar) 14:04:21 INFO - PROCESS | 1652 | RSS: Main: 235556864 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | __start_tp_report 14:04:21 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 14:04:21 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 14:04:21 INFO - PROCESS | 1652 | |i|pagename|runs| 14:04:21 INFO - PROCESS | 1652 | |0;ai-astar;107;121;117;124;117;118;118;120;136;98 14:04:21 INFO - PROCESS | 1652 | |1;audio-beat-detection;145;122;109;148;110;115;113;113;141;113 14:04:21 INFO - PROCESS | 1652 | |2;audio-dft;218;262;193;199;193;207;228;195;225;223 14:04:21 INFO - PROCESS | 1652 | |3;audio-fft;77;60;61;83;52;84;83;85;95;67 14:04:21 INFO - PROCESS | 1652 | |4;audio-oscillator;91;73;101;100;72;69;81;71;71;85 14:04:21 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;88;89;89;88;98;87;95;88;88;88 14:04:21 INFO - PROCESS | 1652 | |6;imaging-darkroom;96;97;105;152;149;96;107;148;106;114 14:04:21 INFO - PROCESS | 1652 | |7;imaging-desaturate;96;82;139;143;92;91;89;89;91;89 14:04:21 INFO - PROCESS | 1652 | |8;json-parse-financial;67;68;67;67;70;71;69;68;68;68 14:04:21 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;57;59;61;67;73;66;60;58;58;58 14:04:21 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;83;85;71;101;115;102;101;88;82;86 14:04:21 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;145;138;135;131;165;152;158;153;190;151 14:04:21 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;174;170;167;165;170;166;167;186;146;199 14:04:21 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;65;67;66;71;61;71;92;83;83 14:04:21 INFO - PROCESS | 1652 | __end_tp_report 14:04:21 INFO - PROCESS | 1652 | __start_cc_report 14:04:21 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1012 14:04:21 INFO - PROCESS | 1652 | __end_cc_report 14:04:21 INFO - PROCESS | 1652 | __startTimestamp1496351061785__endTimestamp 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | ------- Summary: start ------- 14:04:21 INFO - PROCESS | 1652 | Number of tests: 14 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:117.60 Median:120.50 stddev:9.97 (8.3%) stddev-sans-first:9.81 14:04:21 INFO - PROCESS | 1652 | Values: 107.0 121.0 117.0 124.0 117.0 118.0 118.0 120.0 136.0 98.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:118.50 stddev:15.50 (13.1%) stddev-sans-first:14.23 14:04:21 INFO - PROCESS | 1652 | Values: 145.0 122.0 109.0 148.0 110.0 115.0 113.0 113.0 141.0 113.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:214.30 Median:220.50 stddev:21.69 (9.8%) stddev-sans-first:22.96 14:04:21 INFO - PROCESS | 1652 | Values: 218.0 262.0 193.0 199.0 193.0 207.0 228.0 195.0 225.0 223.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:74.70 Median:83.00 stddev:13.85 (16.7%) stddev-sans-first:14.66 14:04:21 INFO - PROCESS | 1652 | Values: 77.0 60.0 61.0 83.0 52.0 84.0 83.0 85.0 95.0 67.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:72.50 stddev:12.31 (17.0%) stddev-sans-first:12.56 14:04:21 INFO - PROCESS | 1652 | Values: 91.0 73.0 101.0 100.0 72.0 69.0 81.0 71.0 71.0 85.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:88.50 stddev:3.65 (4.1%) stddev-sans-first:3.81 14:04:21 INFO - PROCESS | 1652 | Values: 88.0 89.0 89.0 88.0 98.0 87.0 95.0 88.0 88.0 88.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:117.00 Median:150.50 stddev:23.25 (15.4%) stddev-sans-first:23.39 14:04:21 INFO - PROCESS | 1652 | Values: 96.0 97.0 105.0 152.0 149.0 96.0 107.0 148.0 106.0 114.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:100.10 Median:90.00 stddev:21.86 (24.3%) stddev-sans-first:23.13 14:04:21 INFO - PROCESS | 1652 | Values: 96.0 82.0 139.0 143.0 92.0 91.0 89.0 89.0 91.0 89.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:68.00 stddev:1.34 (2.0%) stddev-sans-first:1.33 14:04:21 INFO - PROCESS | 1652 | Values: 67.0 68.0 67.0 67.0 70.0 71.0 69.0 68.0 68.0 68.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:60.50 stddev:5.25 (8.7%) stddev-sans-first:5.29 14:04:21 INFO - PROCESS | 1652 | Values: 57.0 59.0 61.0 67.0 73.0 66.0 60.0 58.0 58.0 58.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:82.50 stddev:12.95 (15.7%) stddev-sans-first:13.38 14:04:21 INFO - PROCESS | 1652 | Values: 83.0 85.0 71.0 101.0 115.0 102.0 101.0 88.0 82.0 86.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:152.50 stddev:17.08 (11.2%) stddev-sans-first:17.94 14:04:21 INFO - PROCESS | 1652 | Values: 145.0 138.0 135.0 131.0 165.0 152.0 158.0 153.0 190.0 151.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:170.00 stddev:13.90 (8.2%) stddev-sans-first:14.70 14:04:21 INFO - PROCESS | 1652 | Values: 174.0 170.0 167.0 165.0 170.0 166.0 167.0 186.0 146.0 199.0 14:04:21 INFO - PROCESS | 1652 | 14:04:21 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:71.00 stddev:9.98 (14.1%) stddev-sans-first:10.38 14:04:21 INFO - PROCESS | 1652 | Values: 67.0 65.0 67.0 66.0 71.0 61.0 71.0 92.0 83.0 83.0 14:04:21 INFO - PROCESS | 1652 | -------- Summary: end -------- 14:04:21 INFO - PROCESS | 1652 | 14:04:22 INFO - TEST-INFO | 1652: exit 0 14:04:22 INFO - TEST-OK | kraken | took 60574ms 14:04:22 INFO - SUITE-END | took 1176s 14:04:22 INFO - Completed test suite (00:19:37) 14:04:22 INFO - javascript benchmark 14:04:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7674.895073989386, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3547.840347184375, 3560.359135355636, 3597.8920632381023, 3603.747737658365, 3628.165521929956, 6038.577299589025, 6092.238470191226, 6113.266408031037, 6145.047517634763, 6160.383961603849, 6032.69836508175, 6096.817095487131, 6127.724252408641, 6153.13856060153, 6162.691865406723, 5439.184122381655, 5501.862453438656, 5533.833984980457, 5535.861603459905, 5601.103823388259, 4640.907181856361, 5552.833414997556, 5577.972110139443, 5580.525655319303, 5584.776608935637, 5386.461353864602, 5415.891682166354, 5511.641743286694, 5560.165975103726, 5567.3040869891265, 4567.931481027787, 4582.747948862811, 5548.0845660466175, 5558.6942718150485, 5637.210790489294, 5416.0, 5517.862053448655, 5537.833864984057, 5564.86087847803, 5579.46995035471, 5911.231539899785, 6140.293866205354, 6143.201383820119, 6219.284782250009, 6254.562180647356, 6321.841953951142, 6328.082428047954, 6350.04749287606, 6414.61512309263, 6436.066770318324, 6605.768798092092, 6606.174228221473, 6616.536842421033, 6618.0, 6625.900611490832, 5917.829996522448, 6268.059791031382, 6268.49852011838, 6282.277538083087, 6315.052742088677, 6401.807945761635, 6482.643454610042, 6571.9671401643645, 6750.493712971449, 6790.558613690095, 6242.0, 6315.052742088632, 6401.135846660642, 6413.486921046305, 6420.935790642127, 5753.424657534213, 6493.480521558264, 6564.310747371457, 6585.0, 6609.306022867626, 6273.654948977759, 6461.127747754087, 6471.126397936218, 6485.77299794505, 6488.124103246096, 5438.857839853596, 5682.261306030189, 6232.781852635227, 6248.719012602405, 6343.651099189589, 4939.75301234944, 4959.479254678206, 4983.252512123138, 5021.84934451967, 5022.9748851256245, 3794.0894185395305, 4448.5773851484055, 4454.309582014793, 4462.241418958784, 4515.729056256635, 5824.446677565625, 5877.177195192677, 5987.161797348374, 6063.54523410746, 6073.878522429615, 6457.3865482779065, 6476.5466417350335, 6505.284418713938, 6537.934620653828, 6568.934310656928, 5284.630075894651, 5727.828165155052, 6112.51099912006, 6126.356732543108, 6133.202683651097, 5113.1052065888325, 5499.890002200013, 5715.657060576379, 5716.0, 5725.971370143123, 5657.43425657431, 6600.943848984236, 6631.834204144935, 6735.158105236846, 6745.696443660146, 5960.314563825215, 6365.172527571386, 6439.130717353098, 6547.050677651619, 6625.768098116543, 5889.411058894077, 6223.595466294676, 6331.303556608677, 6704.631245281556, 6806.863862722619, 5347.839564813062, 6273.059041143865, 6308.495320374267, 6367.522435817425, 6425.903611445926, 6558.376954189344, 6564.606123632598, 6566.277709451861, 6570.770023049362, 6577.440917522064, 6031.306399764083, 6132.264128304632, 6387.457066149429, 6388.041793730792, 6433.163688720623, 6181.474574661024, 6253.280872699515, 6265.0602409637095, 6266.717997690206, 6304.0, 5493.450654934634, 6232.563720539519, 6313.779017734358, 6328.588641738273, 6341.365863413806, 6267.2165979252595, 6296.5504211290145, 6347.143135676624, 6361.363863613602, 6375.458086062737, 6198.473129783839, 6260.843478913063, 6267.185265915402, 6286.402791734778, 6292.11910332563], "value": 5910.194971759285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.3288789421363, 759.6125975752365, 834.1241696218968, 964.4020707161561, 976.8681228034213, 34139.4879076814, 39321.80339098304, 40112.59887401125, 42008.52970146038, 42065.0, 40175.0, 40482.39276410856, 40541.391879121766, 40641.187176256455, 40726.185476290455, 8109.067457242433, 8240.711575094843, 10994.450277486134, 11296.887031129665, 11538.711532211679, 11059.834102488468, 11095.778084438307, 11161.939615736532, 11167.497462614161, 11182.32906025637, 11119.499622517005, 11192.720181995475, 11232.719182020473, 11241.550337986471, 11251.649802023727, 11150.665480035612, 11170.441477926153, 11182.273152245078, 11182.776344473106, 11188.38463884486, 9341.953290233574, 10947.0, 10969.286996345212, 11024.44877756109, 11078.667639970814, 34883.4767478488, 35148.472772908426, 35259.0, 35287.0, 35292.76475323351, 3817.9618203818163, 3846.2884366392027, 3883.7475564088527, 3889.1638297765835, 3948.854832098688, 20654.0, 21163.04766285521, 25935.870320648464, 27320.316992075357, 27592.448151036868, 2752.692471076243, 3152.7477801775804, 3159.210197450637, 3181.4114388837907, 3197.7441804655573, 294715.99999999785, 325244.49511009885, 330195.0, 383116.0, 385918.0, 43874.78062609667, 44086.77956610196, 44148.67553973386, 44233.0, 44354.77822610931, 38696.0, 45862.31206531905, 46124.8468788283, 46183.0, 46296.07407851891, 13851.653708657162, 13991.510297139552, 14025.859741402659, 14039.859601403856, 14115.153090814583, 12118.0, 12241.265524068584, 12277.447514861853, 12302.876971230173, 12327.137100403064, 11754.11844111695, 11807.586734464252, 11810.232334898204, 11884.76230475403, 11938.22401543897, 5659.801906933238, 5705.058665320175, 5719.942800572025, 5732.16883551891, 5745.3105627324985, 1089.3300620118728, 1092.9780655087518, 1095.7370231144469, 1114.147677027075, 1233.5374234662002, 29211.415771684446, 29749.256268593457, 29954.55068173979, 29962.85018574937, 30535.541966870514, 1065.4033741105006, 1070.9518071686637, 1101.1136035491347, 1116.6984914073155, 1117.6423544465856, 10824.203322976866, 13350.933245333712, 13834.031617786659, 14020.439182432588, 14038.0, 498560.0, 502591.97408026183, 503347.0, 503726.48136759084, 504264.0, 47394.289085663746, 47510.5248947513, 47579.5242047582, 47725.5227447728, 48032.5196748035, 1399.6640806206437, 1425.4369524037947, 1433.025542631, 1437.3531910640254, 1440.827100747917, 20518.89740551288, 25664.973401063748, 26248.475030499663, 26480.867595661897, 26722.0, 40949.795251023555, 41287.793561032006, 41338.0, 41386.79306603448, 41395.965100872716, 484688.57655712904, 486225.0, 486766.0, 487124.0, 487276.0, 29183.708162918098, 29495.55756663609, 30873.536896946116, 31204.063878083693, 32516.861909833042, 39451.01372465711, 39659.40510892281, 39912.401313980896, 40475.19049618932, 40615.3907691379, 22270.665940011237, 28258.85870570634, 29453.0, 29726.25684357822, 29825.403491930472, 28963.565546516398, 29132.417351653272, 29818.552721709624, 29837.70162298436, 30094.0, 33654.49518257277, 34425.65574344224, 34809.47785783165, 35074.29851403009, 35165.64834351726, 28184.872605095567, 29097.272568185967, 29635.703642963294, 30023.3995320088, 30091.097267082023, 20400.59198816045, 23853.926573303896, 24044.15845445401, 24297.878510607334, 24361.025558977442], "value": 18395.381929098676, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.360319554018, 403.1252182763404, 408.59957241902913, 412.36289932054973, 454.06915822563735, 20496.385108446753, 20729.85985770782, 21290.680639790415, 21722.565548689017, 21728.239511617096, 21494.785052149473, 21643.567128657418, 21767.3469795906, 22350.21774237902, 22593.983270752815, 6707.1616047994, 6987.89518157228, 6994.230634630187, 7046.788596342092, 7059.08231929851, 6872.518923675345, 7073.0, 7413.740519081834, 7545.245475452438, 7559.9416081748595, 7311.341979221869, 7509.436792240576, 7514.39884809214, 7521.398288136936, 7532.435067369942, 7287.34413902746, 7589.620518974057, 7637.236276372374, 7670.0795904491815, 7677.193894641066, 6115.602485838451, 6497.837554061139, 7294.744683936036, 7364.521306115139, 7386.409087273005, 23687.526249475086, 24307.513849722905, 25635.871820640965, 25979.0, 26210.213693589223, 2494.8128890333114, 2922.941541169186, 2993.4162838246552, 3009.6388433387915, 3019.562163486304, 15508.0, 15575.37698492059, 15662.9216853915, 15672.843271567366, 16026.0, 2053.3224036068063, 2112.4613223627875, 2113.936581902545, 2116.7354080739906, 2121.427214652035, 101544.79711731219, 107967.0, 139171.30414347965, 142958.0, 144066.0, 20378.69431958522, 21055.157793688388, 21142.365729028155, 21337.573248534944, 21342.35972920815, 24850.50298994046, 30808.075757727303, 31245.375092498023, 31319.0, 31328.686713132578, 8447.619857106445, 10262.025107614765, 10304.021117993778, 10478.528466218886, 10643.893561064291, 8124.821900824466, 8212.876806847902, 8715.651373945098, 8731.126887311219, 8912.8663070054, 8633.481991080555, 8825.249853762374, 9501.667441639647, 9521.047895210562, 9682.225421966226, 3573.9285214296087, 3912.9043867717087, 3955.861544845917, 4153.68847336451, 4336.34954756789, 906.1210625693067, 1029.9279050466546, 1032.7108409645311, 1038.2420832792104, 1052.742078190833, 17404.82595174032, 18194.18126184325, 18436.801607895442, 20428.897855510626, 20629.277975270794, 891.4695756025154, 1026.973026973027, 1028.7222449938624, 1055.6885718712997, 1060.316096118015, 10399.84400233997, 10744.194185436123, 10857.619983300547, 10893.07408870255, 10898.07366373867, 603198.0, 732822.0, 735313.0, 741772.0, 759329.0, 122568.0, 127352.36323818321, 133497.33251333874, 137636.0, 149155.0, 1227.1839226914003, 1232.6302109367155, 1252.8997680185564, 1255.9686007849878, 1261.4008346035744, 14834.851651483346, 17172.0, 17355.045472499132, 17762.822371776634, 17985.10074496296, 58071.83856322934, 59300.70349648224, 59569.404305956385, 60106.0, 60157.699211505416, 76408.61795690986, 81576.59211703904, 83167.16832831837, 84213.0, 85534.7169792466, 9280.72157835266, 9942.669205816133, 11481.425928703364, 11528.0, 11830.46762895689, 12485.87514124847, 12641.557545485864, 12686.936565317113, 12721.8091728626, 12734.74530509366, 9535.809283814424, 10685.893141068489, 11012.0, 11054.889451105708, 11108.166887483309, 10312.845307320245, 10435.530401132119, 10708.625198118028, 10744.355338679705, 10747.247692661438, 11418.429078545872, 11514.0, 11533.1926765129, 11807.76384472323, 12001.81997270059, 9000.954995225244, 11321.150913681342, 11466.197366184288, 11528.308301501937, 11825.645230643095, 10808.513616887101, 10929.83605245906, 10933.781324373627, 10967.122630189726, 11347.54609815598], "value": 11379.440825807722, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.1437137717367, 1547.1490680125928, 1601.9507222769091, 1611.234663534821, 1612.5081850035742, 9375.296852736037, 9586.281028922833, 9691.370060946034, 9750.756231094227, 11155.665330040112, 10684.358938463678, 11039.448027598628, 11046.944765276183, 11059.944700276508, 11074.058705010084, 2010.5576773109892, 2357.316378250305, 2405.1101092595677, 2413.1433341163897, 2414.4567472318677, 2365.8580485170946, 2393.3537944754908, 2397.844140130886, 2407.843490173142, 2410.7348191698898, 2370.905163793455, 2383.5828729972272, 2390.0320370249988, 2391.1989483522952, 2395.556821987929, 1928.469670840516, 2324.4537533679572, 2341.274204996457, 2342.61346877765, 2344.2732752846673, 2009.9196032158695, 2357.7877990980724, 2360.221127028077, 2367.2306500387444, 2368.052778888441, 9188.586513606837, 9435.528223588793, 11218.663440096729, 11246.775064498746, 11258.887411125866, 5698.116791897262, 5850.0932355484965, 5884.176215329857, 5886.499647529965, 5890.970545147246, 2938.2213713365977, 3499.177693242086, 3695.0762309422644, 3719.088823238324, 3721.79530125843, 1157.4212893553224, 1229.643403413009, 1230.2065167966612, 1234.2841152131741, 1268.6384380451527, 15376.154311512973, 15398.769018464733, 15418.997765145341, 15451.768223476545, 15480.922595387063, 4720.740359280204, 4892.143874821963, 4900.093482705711, 4979.526944940225, 5007.298978143063, 12947.611571652866, 12959.870401296055, 12963.22220666763, 12964.351782410842, 12978.545750898673, 5756.942430575725, 5775.047117225695, 5830.5627077968475, 5983.252093488314, 5983.880322393527, 4426.64586833059, 4906.730129842893, 5116.769745361467, 5191.402988656278, 5246.632735708464, 4001.0597509585223, 4098.913787846223, 4123.649489793401, 4173.812178451977, 4195.91608167835, 1104.2877344113, 1107.0755918807886, 1112.7941330853819, 1122.7922834275685, 1124.516457923101, 944.6599224279254, 966.1884017425397, 971.1308379000754, 977.9070988256104, 1007.2596641468662, 6560.0, 6640.335966403321, 6652.101966234496, 6855.040294358794, 6889.827754306082, 985.3053597213947, 1005.3565717940523, 1017.811704834608, 1024.7438140464883, 1041.57295508841, 3539.823008849599, 4226.4505614270565, 4274.722143060691, 4342.240107981153, 4347.500037495728, 9581.616735330648, 12323.938380308042, 12332.50669973191, 12386.08015757559, 12401.875981240253, 11875.940620297017, 12050.156489045683, 12127.514899403925, 12287.324197169242, 12315.13794034427, 9249.768755781159, 9251.398659087003, 9333.0, 9364.812703746024, 9393.0, 5370.785168593213, 5679.176519404746, 6230.813075607739, 6263.185785847811, 6275.3724627536885, 10850.023497885228, 10965.451727413756, 10969.067629251604, 11093.889061109285, 11172.106231501622, 11137.331760094421, 11214.215004949576, 11385.544578216779, 11426.25729327591, 11619.186656933933, 7398.815029624302, 8079.919200808152, 8339.041010283894, 8370.497770133812, 8569.400141990001, 10171.626830377805, 10496.475176241005, 10538.683839484827, 10546.419946902994, 10561.735956601147, 6681.799546013627, 8060.193980601893, 8825.528172533071, 8834.425762325429, 8863.778405539655, 7176.569405835667, 7389.261073892568, 8700.782480437787, 8769.254613357936, 8799.032106468412, 7770.222977702185, 7804.0635123785505, 7857.764267071997, 7861.724839630586, 7875.330596899328, 5805.274340707411, 5818.883622327615, 5833.212516310244, 5993.340732519332, 6069.514438844793, 5938.020226662552, 6074.817755467343, 6108.908366374419, 6138.508919286357, 6189.35011823752], "value": 5028.836443594006, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.7614326830829, 386.7099675243567, 388.4910766895367, 393.0586245940973, 397.65404098434357, 4882.316475693406, 4951.93533390321, 5475.151351540512, 5494.263900547626, 5532.944670553298, 4809.783559739812, 5112.514311140445, 5244.501772331632, 5297.311349524556, 5300.886813769103, 3612.4039533476944, 4219.278565325371, 4305.504866940295, 4417.845375411861, 4429.579189976947, 3554.4312909934415, 3605.36906041443, 4267.818659529023, 4326.199653064194, 4342.348647702838, 3902.570717221103, 4301.8064187111495, 4326.286162783137, 4334.328179132223, 4357.891052723691, 3693.722970777189, 4333.359977618816, 4375.431193944799, 4382.386465894777, 4417.293233082723, 3359.445691460906, 3490.359531020442, 3783.6405541473514, 4021.577734337911, 4042.9797851010558, 4165.812538435777, 4372.950491881926, 5131.820386286462, 5181.5077567631015, 5190.195519694454, 4132.524759652649, 4195.370694395834, 4894.136929896015, 5139.428097472258, 5199.766010529535, 5298.317669219894, 5384.892302153974, 5713.628614140109, 5730.856728581777, 5747.0, 3561.4863682934647, 3973.443717879499, 4209.74741515507, 4214.978925105385, 4541.772911354419, 5288.074586947308, 5705.837695289078, 5927.229460170193, 6185.721642526097, 6191.535634827361, 3988.8205030773684, 4381.496127945327, 4674.626029917598, 4689.459458109174, 4737.763111844394, 4287.399764033038, 4763.642726795435, 4772.0, 4777.761111944389, 4789.976050119727, 4788.880277993078, 4911.705297682151, 4995.325631039763, 5001.374278503815, 5133.432264576084, 4486.349479325545, 4714.504976977437, 5074.923876141861, 5214.765335559909, 5331.626786125012, 1483.5845963130341, 1508.3363320139104, 1576.085870195284, 1585.7304258276122, 1625.9756103658456, 988.6539711101201, 1217.3565686323393, 1219.3720234079456, 1234.456838990841, 1241.8698983924767, 4540.54810402379, 4607.58531732139, 4738.905221895543, 5171.870703232449, 5171.974140129275, 4677.3919390479705, 5542.251796007568, 5573.972130139405, 5751.971240143856, 5892.882142357129, 4614.353990441341, 4783.6605750389945, 5030.0694371541385, 5087.3386459759995, 5133.6385262778795, 2456.606942889129, 2920.956185657217, 2957.6746557878614, 3049.2798785481677, 3092.226943264184, 5321.255024296602, 5432.777625034336, 5914.792982245687, 6057.759098882077, 6132.724027418777, 4936.851894443173, 5214.97392513035, 5583.497485226349, 5696.715164241854, 6102.0, 5239.952009598096, 5523.6685798852195, 5819.098039803837, 6188.019833653188, 6229.875402491834, 4920.769807548113, 5239.738013099406, 5282.709450980233, 5888.705564721832, 5939.524838013035, 5438.809641662523, 5591.440855914376, 5785.710714464176, 5841.744025034638, 5873.2658417697785, 5625.774969001194, 5803.448672376117, 5929.881402371842, 6023.728932197974, 6227.937720622917, 3986.5614782373336, 4168.958310416979, 4269.893252668709, 4462.933056004098, 4631.073785242897, 5031.320771695774, 5168.508991645787, 5417.0, 5645.983722929748, 5842.211301474246, 4916.361892199675, 5259.4477579853565, 5486.0, 5605.82277721683, 5688.886222275614, 5193.714345711022, 5359.812406565751, 5364.222187782828, 5732.426757324234, 5751.424857514216, 4685.180093483695, 5256.421793602777, 5282.339707536558, 5529.686844261482, 5567.944320556742, 4855.295982082649, 5052.823151189691, 5149.56228720548, 5643.717814109196, 5739.0, 4731.6214702823, 5359.839204823861, 5380.892382152257, 5453.7818487260065, 5635.380108188014], "value": 4259.819584432544, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.2450202785047, 726.614894106124, 733.3106879533238, 801.0427539090786, 863.7149740585605, 15905.363785448582, 16537.7596680249, 16823.91588042058, 17010.744838827413, 17198.570035749082, 15310.234488275597, 15650.139242341667, 15674.0, 15836.604084897883, 15864.841351586481, 959.4051687953461, 986.8914419413894, 987.0770829274616, 987.6345752071708, 988.4415305352488, 953.9427634341927, 979.2166266986418, 983.2281658897766, 987.8221920054388, 995.1839491616877, 1026.563710423071, 1027.4349107990613, 1028.300755486269, 1028.6296933104077, 1030.9226807989394, 1023.5189460953377, 1034.3018462537796, 1034.591336422109, 1035.7410647338165, 1035.7824856780085, 772.3203580848817, 774.2335088262636, 774.767569729079, 775.1241097559778, 776.8368642585064, 15437.768433473508, 16362.445567671051, 16609.6678066438, 16932.492025239262, 16979.0, 14245.715085698332, 14447.277636118151, 14511.274436278143, 14535.78196327056, 14544.927275363556, 11687.474063667156, 11735.0, 11816.763664726743, 11860.288382697066, 12429.31638759868, 3639.162992511711, 3809.847606095753, 3839.2897313997, 3926.587708290617, 3935.8032098394965, 109696.4515177427, 116129.0, 116434.0, 117780.0, 117809.41095294555, 11112.666620001413, 11132.10943124548, 11160.497777599865, 11326.980571748418, 11433.942830285961, 18741.437756867315, 23231.88384058069, 23413.946372413284, 23482.765172348398, 23561.29316120519, 7996.600169991477, 8005.519668819889, 8072.475289106189, 8126.431149819456, 8270.751877443687, 4115.218108559364, 4230.386593943923, 4294.334378171388, 4467.441569803775, 4552.226121559343, 3405.3019131078067, 3469.358168738741, 3493.458513930345, 3498.422760244531, 3501.842417091237, 619.454879705856, 627.7300760672955, 642.3351830855042, 643.8519140597689, 651.3258777165611, 632.5287660692721, 647.7441410642864, 664.9468042556584, 673.5588189735731, 683.8803209438329, 8882.378233523721, 8891.0, 9877.308588398886, 10045.29682922203, 10194.949025254975, 647.9222493300834, 667.452688795193, 670.7356632747307, 674.308833445722, 691.7094820175538, 4597.586764522951, 4852.733099679481, 4873.463918968911, 4904.313396124546, 4914.36113305268, 80561.19438805654, 91770.08229917749, 99010.0, 99274.0, 99869.0, 78609.60695196487, 81756.18243817605, 84816.0, 85787.57106214554, 86885.565572173, 75547.0, 77539.0, 77999.61000194962, 79301.60349198217, 80383.0, 8953.418027828171, 9203.723888283363, 9394.483303418378, 9478.336516443782, 9513.857292140485, 46694.0, 51278.0, 51716.22425663693, 51956.70108247324, 52745.68135796636, 47860.760696196296, 62040.0, 66814.0, 66842.99735503874, 69319.0, 12761.809547613097, 13042.347882606022, 13144.474221031052, 13618.114822536503, 13842.169469831842, 13434.193948363129, 14016.528264532077, 14888.776668349767, 15174.848251517344, 15400.614984625474, 13707.451701931812, 13767.629615268188, 13845.515406960709, 14016.509422170582, 14197.077189982618, 13837.446502139801, 13882.0, 13927.442902283796, 14034.649133771738, 14528.709425811214, 13043.673908152372, 13551.0, 13739.313034348042, 13748.862511375159, 13855.930720346334, 9829.754256143653, 9869.210463163074, 10040.196784257096, 10076.647317343857, 10603.204759643211, 10831.2959657622, 11291.322520648788, 11343.035841953526, 11567.421628918688, 11572.59495917639], "value": 7710.882109576903, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 121.0, 117.0, 124.0, 117.0, 118.0, 118.0, 120.0, 136.0, 98.0], "value": 117.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 122.0, 109.0, 148.0, 110.0, 115.0, 113.0, 113.0, 141.0, 113.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 262.0, 193.0, 199.0, 193.0, 207.0, 228.0, 195.0, 225.0, 223.0], "value": 214.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 60.0, 61.0, 83.0, 52.0, 84.0, 83.0, 85.0, 95.0, 67.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 73.0, 101.0, 100.0, 72.0, 69.0, 81.0, 71.0, 71.0, 85.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 88.0, 98.0, 87.0, 95.0, 88.0, 88.0, 88.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 105.0, 152.0, 149.0, 96.0, 107.0, 148.0, 106.0, 114.0], "value": 117.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 82.0, 139.0, 143.0, 92.0, 91.0, 89.0, 89.0, 91.0, 89.0], "value": 100.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 70.0, 71.0, 69.0, 68.0, 68.0, 68.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 61.0, 67.0, 73.0, 66.0, 60.0, 58.0, 58.0, 58.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 71.0, 101.0, 115.0, 102.0, 101.0, 88.0, 82.0, 86.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 138.0, 135.0, 131.0, 165.0, 152.0, 158.0, 153.0, 190.0, 151.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 167.0, 165.0, 170.0, 166.0, 167.0, 186.0, 146.0, 199.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 66.0, 71.0, 61.0, 71.0, 92.0, 83.0, 83.0], "value": 72.6, "unit": "score"}]}]} 14:04:22 INFO - Return code: 0 14:04:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:04:23 ERROR - # TBPL SUCCESS # 14:04:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:04:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:04:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:04:23 INFO - 2017-06-01 14:04:23.075 screenresolution[1657:10689] starting screenresolution argv=screenresolution get 14:04:23 INFO - 2017-06-01 14:04:23.078 screenresolution[1657:10689] Display 0: 1600x1200x32@60 14:04:23 INFO - 2017-06-01 14:04:23.084 screenresolution[1658:10692] starting screenresolution argv=screenresolution list 14:04:23 INFO - Available Modes on Display 0 14:04:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:04:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:04:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:04:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:04:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:04:23 INFO - Intel Iris: 14:04:23 INFO - Chipset Model: Intel Iris 14:04:23 INFO - Type: GPU 14:04:23 INFO - Bus: Built-In 14:04:23 INFO - VRAM (Dynamic, Max): 1536 MB 14:04:23 INFO - Vendor: Intel (0x8086) 14:04:23 INFO - Device ID: 0x0a2e 14:04:23 INFO - Revision ID: 0x0009 14:04:23 INFO - Displays: 14:04:23 INFO - Display: 14:04:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:04:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:04:23 INFO - Display Serial Number: 1600x1200 60 14:04:23 INFO - Main Display: Yes 14:04:23 INFO - Mirror: Off 14:04:23 INFO - Online: Yes 14:04:23 INFO - Rotation: Supported 14:04:23 INFO - Return code: 0 14:04:23 INFO - Running post-action listener: _resource_record_post_action 14:04:23 INFO - [mozharness: 2017-06-01 21:04:23.323846Z] Finished run-tests step (success) 14:04:23 INFO - Running post-run listener: _resource_record_post_run 14:04:23 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 35471360; Write bytes: 509963264; Read time: 325; Write time: 1751 14:04:23 INFO - TinderboxPrint: CPU usage
54.3% 14:04:23 INFO - TinderboxPrint: I/O read bytes / time
35,471,360 / 325 14:04:23 INFO - TinderboxPrint: I/O write bytes / time
509,963,264 / 1,751 14:04:23 INFO - TinderboxPrint: CPU idle
2,186.7 (45.7%) 14:04:23 INFO - TinderboxPrint: CPU system
958.7 (20.0%) 14:04:23 INFO - TinderboxPrint: CPU user
1,644.4 (34.3%) 14:04:23 INFO - TinderboxPrint: Swap in / out
240,390,144 / 0 14:04:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206383616; Write bytes: 201328640; Read time: 15673; Write time: 414 14:04:23 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 33357824; Write bytes: 297563136; Read time: 300; Write time: 1299 14:04:23 INFO - Running post-run listener: _upload_blobber_files 14:04:23 INFO - Blob upload gear active. 14:04:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:04:23 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 14:04:23 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'] 14:04:23 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 14:04:24 INFO - (blobuploader) - INFO - Open directory for files ... 14:04:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:04:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:04:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:25 INFO - (blobuploader) - INFO - Done attempting. 14:04:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:04:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:04:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:26 INFO - (blobuploader) - INFO - Done attempting. 14:04:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:04:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:04:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:27 INFO - (blobuploader) - INFO - Done attempting. 14:04:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:04:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:04:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:30 INFO - (blobuploader) - INFO - Done attempting. 14:04:30 INFO - (blobuploader) - INFO - Iteration through files over. 14:04:30 INFO - Return code: 0 14:04:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:04:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:04:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e3755c976ed7a678c4a95b5df231fec1f043f055abff5fe06335cd44d7144505759c6fc8d7e85c4f7788f9b65429aa5d71622e34f481cdc27e95bb19739b170", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d080c9a391fafb104aa40dac29d97f5dd0b0de0131dd099b638f5b52a63893017648694b46164b15882483afae8e5d0db12550e32d867ddcc4e603fc4ed3267a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0b68601f4fd3e8a9af41ecd48d3e2f0bc44e6a71a2e4c80fd323685cd3d5059f0b1ef6d85680ee26e2f46ee8977d8a2dc6baa8803874c44322add616003079da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54cdf8651a23260a2761c5ea80772e4133ce778556ec94b5c06b85afdad64d1e2acb48b9beb522d5b7da3c21cc32ed1a81ce6c239aae4085ff75c550467f4f9c"} 14:04:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:04:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:04:30 INFO - Contents: 14:04:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e3755c976ed7a678c4a95b5df231fec1f043f055abff5fe06335cd44d7144505759c6fc8d7e85c4f7788f9b65429aa5d71622e34f481cdc27e95bb19739b170", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d080c9a391fafb104aa40dac29d97f5dd0b0de0131dd099b638f5b52a63893017648694b46164b15882483afae8e5d0db12550e32d867ddcc4e603fc4ed3267a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0b68601f4fd3e8a9af41ecd48d3e2f0bc44e6a71a2e4c80fd323685cd3d5059f0b1ef6d85680ee26e2f46ee8977d8a2dc6baa8803874c44322add616003079da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54cdf8651a23260a2761c5ea80772e4133ce778556ec94b5c06b85afdad64d1e2acb48b9beb522d5b7da3c21cc32ed1a81ce6c239aae4085ff75c550467f4f9c"} 14:04:30 INFO - Running post-run listener: copy_logs_to_upload_dir 14:04:30 INFO - Copying logs to upload dir... 14:04:30 INFO - mkdir: /builds/slave/test/build/upload/logs 14:04:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.180861 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-06-01 14:04:30.502842) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 14:04:30.507407) ========= 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.NOVkG1keXh/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.D36R5tP2Tb/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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/3e3755c976ed7a678c4a95b5df231fec1f043f055abff5fe06335cd44d7144505759c6fc8d7e85c4f7788f9b65429aa5d71622e34f481cdc27e95bb19739b170", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d080c9a391fafb104aa40dac29d97f5dd0b0de0131dd099b638f5b52a63893017648694b46164b15882483afae8e5d0db12550e32d867ddcc4e603fc4ed3267a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0b68601f4fd3e8a9af41ecd48d3e2f0bc44e6a71a2e4c80fd323685cd3d5059f0b1ef6d85680ee26e2f46ee8977d8a2dc6baa8803874c44322add616003079da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54cdf8651a23260a2761c5ea80772e4133ce778556ec94b5c06b85afdad64d1e2acb48b9beb522d5b7da3c21cc32ed1a81ce6c239aae4085ff75c550467f4f9c"} build_url:https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011045 build_url: 'https://queue.taskcluster.net/v1/task/OQMG6_neT3SJKfnEM1W_8A/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/3e3755c976ed7a678c4a95b5df231fec1f043f055abff5fe06335cd44d7144505759c6fc8d7e85c4f7788f9b65429aa5d71622e34f481cdc27e95bb19739b170", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d080c9a391fafb104aa40dac29d97f5dd0b0de0131dd099b638f5b52a63893017648694b46164b15882483afae8e5d0db12550e32d867ddcc4e603fc4ed3267a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0b68601f4fd3e8a9af41ecd48d3e2f0bc44e6a71a2e4c80fd323685cd3d5059f0b1ef6d85680ee26e2f46ee8977d8a2dc6baa8803874c44322add616003079da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/54cdf8651a23260a2761c5ea80772e4133ce778556ec94b5c06b85afdad64d1e2acb48b9beb522d5b7da3c21cc32ed1a81ce6c239aae4085ff75c550467f4f9c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 14:04:30.545617) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:04:30.545969) ========= 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.NOVkG1keXh/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.D36R5tP2Tb/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.005038 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:04:30.594586) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 14:04:30.594917) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 14:04:30.595259) ========= ========= Total master_lag: 0.19 =========