builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0023 starttime: 1495019083.93 results: success (0) revision: 6e3ca5b38f7173b214b10de49e58cb01890bf39d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:43.934183) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:43.934741) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:43.935065) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:43.961228) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:43.961817) ========= 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.95Ip9MFO4o/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.LagkcXus3a/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-17 04:04:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.2M=0.001s 2017-05-17 04:04:44 (14.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116240 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:44.236406) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:44.236746) ========= 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.95Ip9MFO4o/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.LagkcXus3a/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.027970 ========= master_lag: 0.19 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:44.458745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:44.459043) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6e3ca5b38f7173b214b10de49e58cb01890bf39d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 6e3ca5b38f7173b214b10de49e58cb01890bf39d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.95Ip9MFO4o/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.LagkcXus3a/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-17 04:04:44,548 truncating revision to first 12 chars 2017-05-17 04:04:44,548 Setting DEBUG logging. 2017-05-17 04:04:44,548 attempt 1/10 2017-05-17 04:04:44,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6e3ca5b38f71?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-17 04:04:45,227 unpacking tar archive at: mozilla-central-6e3ca5b38f71/testing/mozharness/ program finished with exit code 0 elapsedTime=0.949847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:45.432006) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:45.432381) ========= script_repo_revision: 6e3ca5b38f7173b214b10de49e58cb01890bf39d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:45.432809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:45.433157) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 04:04:45.452323) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-17 04:04:45.452841) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.95Ip9MFO4o/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.LagkcXus3a/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:04:45 INFO - MultiFileLogger online at 20170517 04:04:45 in /builds/slave/test 04:04:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:04:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:04:45 INFO - {'append_to_log': False, 04:04:45 INFO - 'base_work_dir': '/builds/slave/test', 04:04:45 INFO - 'blob_upload_branch': 'Firefox', 04:04:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:04:45 INFO - 'branch': 'Firefox', 04:04:45 INFO - 'buildbot_json_path': 'buildprops.json', 04:04:45 INFO - 'config_files': ('talos/mac_config.py',), 04:04:45 INFO - 'default_actions': ('clobber', 04:04:45 INFO - 'read-buildbot-config', 04:04:45 INFO - 'download-and-extract', 04:04:45 INFO - 'populate-webroot', 04:04:45 INFO - 'create-virtualenv', 04:04:45 INFO - 'install', 04:04:45 INFO - 'run-tests'), 04:04:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:04:45 INFO - 'download_minidump_stackwalk': True, 04:04:45 INFO - 'download_symbols': 'ondemand', 04:04:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:04:45 INFO - 'tooltool.py': '/tools/tooltool.py', 04:04:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:04:45 INFO - '/tools/misc-python/virtualenv.py')}, 04:04:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:04:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:04:45 INFO - 'gecko_profile': False, 04:04:45 INFO - 'gecko_profile_interval': 0, 04:04:45 INFO - 'installer_path': 'installer.exe', 04:04:45 INFO - 'log_level': 'info', 04:04:45 INFO - 'log_name': 'talos', 04:04:45 INFO - 'log_to_console': True, 04:04:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:04:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:04:45 INFO - 'opt_config_files': (), 04:04:45 INFO - 'pip_index': False, 04:04:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:04:45 INFO - 'cmd': ('bash', 04:04:45 INFO - '-c', 04:04:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:04:45 INFO - 'enabled': True, 04:04:45 INFO - 'halt_on_failure': False, 04:04:45 INFO - 'name': 'check_screen_resolution'},), 04:04:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:04:45 INFO - 'cmd': ('bash', 04:04:45 INFO - '-c', 04:04:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:04:45 INFO - 'enabled': True, 04:04:45 INFO - 'halt_on_failure': False, 04:04:45 INFO - 'name': 'check_screen_resolution'},), 04:04:45 INFO - 'run_cmd_checks_enabled': True, 04:04:45 INFO - 'suite': 'dromaeojs-e10s', 04:04:45 INFO - 'system_bits': '32', 04:04:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:04:45 INFO - 'title': 't-yosemite-r7-0023', 04:04:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:04:45 INFO - 'use_talos_json': True, 04:04:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:04:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:04:45 INFO - 'work_dir': 'build'} 04:04:45 INFO - [mozharness: 2017-05-17 11:04:45.653033Z] Running clobber step. 04:04:45 INFO - Running pre-action listener: _resource_record_pre_action 04:04:45 INFO - Running main action method: clobber 04:04:45 INFO - rmtree: /builds/slave/test/build 04:04:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:04:48 INFO - Running post-action listener: _resource_record_post_action 04:04:48 INFO - [mozharness: 2017-05-17 11:04:48.152211Z] Finished clobber step (success) 04:04:48 INFO - [mozharness: 2017-05-17 11:04:48.152340Z] Running read-buildbot-config step. 04:04:48 INFO - Running pre-action listener: _resource_record_pre_action 04:04:48 INFO - Running main action method: read_buildbot_config 04:04:48 INFO - Using buildbot properties: 04:04:48 INFO - { 04:04:48 INFO - "project": "", 04:04:48 INFO - "product": "firefox", 04:04:48 INFO - "who": "cbook@mozilla.com", 04:04:48 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 04:04:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:04:48 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:04:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:04:48 INFO - "stage_platform": "macosx64", 04:04:48 INFO - "basedir": "/builds/slave/test", 04:04:48 INFO - "buildnumber": 66, 04:04:48 INFO - "platform": "yosemite_r7", 04:04:48 INFO - "parent_task_id": "SYfxtJtKRe2N5dp7upYKYg", 04:04:48 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:04:48 INFO - "slavebuilddir": "test", 04:04:48 INFO - "taskId": "BOTib7_7SSa8QVMcqkX5-Q", 04:04:48 INFO - "branch": "mozilla-central", 04:04:48 INFO - "script_repo_revision": "production", 04:04:48 INFO - "revision": "6e3ca5b38f7173b214b10de49e58cb01890bf39d", 04:04:48 INFO - "slavename": "t-yosemite-r7-0023", 04:04:48 INFO - "repo_path": "mozilla-central" 04:04:48 INFO - } 04:04:48 INFO - Finding installer, test and symbols from parent task. 04:04:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BOTib7_7SSa8QVMcqkX5-Q'}, attempt #1 04:04:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BOTib7_7SSa8QVMcqkX5-Q'}, attempt #1 04:04:49 INFO - Task dependencies: aJKxFOYOTGiBIlbhN8A6Pg 04:04:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg'}, attempt #1 04:04:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:04:49 INFO - Set test_url: https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/test_packages.json 04:04:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/target.crashreporter-symbols.zip 04:04:49 INFO - Running post-action listener: _resource_record_post_action 04:04:49 INFO - [mozharness: 2017-05-17 11:04:49.927555Z] Finished read-buildbot-config step (success) 04:04:49 INFO - [mozharness: 2017-05-17 11:04:49.927693Z] Running download-and-extract step. 04:04:49 INFO - Running pre-action listener: _resource_record_pre_action 04:04:49 INFO - Running main action method: download_and_extract 04:04:49 INFO - mkdir: /builds/slave/test/build/tests 04:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:04:49 INFO - trying https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:04:49 INFO - Downloading https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:04:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:04:50 INFO - Downloaded 1325 bytes. 04:04:50 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:04:50 INFO - Using the following test package requirements: 04:04:50 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:04:50 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:04:50 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:04:50 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:04:50 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'jsshell-mac.zip'], 04:04:50 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:04:50 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:04:50 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:04:50 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:04:50 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:04:50 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:04:50 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:04:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:04:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:04:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:04:50 INFO - Fetch https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:04:52 INFO - Content-Length response header: 56567192 04:04:52 INFO - Bytes received: 56567192 04:05:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:05:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:05:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:05:09 INFO - Fetch https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:05:11 INFO - Content-Length response header: 56567192 04:05:11 INFO - Bytes received: 56567192 04:05:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:05:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:05:28 INFO - Fetch https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:05:29 INFO - Content-Length response header: 14410069 04:05:29 INFO - Bytes received: 14410069 04:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:30 INFO - trying https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:05:30 INFO - Downloading https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:05:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:05:32 INFO - Downloaded 75913838 bytes. 04:05:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:05:32 INFO - mkdir: /builds/slave/test/properties 04:05:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:05:32 INFO - Writing to file /builds/slave/test/properties/build_url 04:05:32 INFO - Contents: 04:05:32 INFO - build_url:https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:05:32 INFO - Running post-action listener: _resource_record_post_action 04:05:32 INFO - Running post-action listener: set_extra_try_arguments 04:05:32 INFO - [mozharness: 2017-05-17 11:05:32.446959Z] Finished download-and-extract step (success) 04:05:32 INFO - [mozharness: 2017-05-17 11:05:32.447080Z] Running populate-webroot step. 04:05:32 INFO - Running pre-action listener: _resource_record_pre_action 04:05:32 INFO - Running main action method: populate_webroot 04:05:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:05:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:05:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:05:32 INFO - '--tppagecycles', 04:05:32 INFO - '7'], 04:05:32 INFO - 'tests': ['tsvgm']}}, 04:05:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:05:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:05:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:05:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:05:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:05:32 INFO - 'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:05:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:05:32 INFO - 'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['damp', 'tps']}, 04:05:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:05:32 INFO - 'tests': ['damp', 'tps']}, 04:05:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['dromaeo_dom']}, 04:05:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:05:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:05:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:05:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:05:32 INFO - 'tests': ['ts_paint', 'tp5o']}, 04:05:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['a11yr', 04:05:32 INFO - 'ts_paint', 04:05:32 INFO - 'tpaint', 04:05:32 INFO - 'sessionrestore', 04:05:32 INFO - 'sessionrestore_no_auto_restore', 04:05:32 INFO - 'tabpaint']}, 04:05:32 INFO - 'other-e10s': {'tests': ['a11yr', 04:05:32 INFO - 'ts_paint', 04:05:32 INFO - 'tpaint', 04:05:32 INFO - 'sessionrestore', 04:05:32 INFO - 'sessionrestore_no_auto_restore', 04:05:32 INFO - 'tabpaint']}, 04:05:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:05:32 INFO - 'ts_paint', 04:05:32 INFO - 'tpaint', 04:05:32 INFO - 'sessionrestore', 04:05:32 INFO - 'sessionrestore_no_auto_restore', 04:05:32 INFO - 'tabpaint']}, 04:05:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:05:32 INFO - 'ts_paint', 04:05:32 INFO - 'tpaint', 04:05:32 INFO - 'sessionrestore', 04:05:32 INFO - 'sessionrestore_no_auto_restore', 04:05:32 INFO - 'tabpaint']}, 04:05:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['a11yr', 04:05:32 INFO - 'ts_paint', 04:05:32 INFO - 'tpaint', 04:05:32 INFO - 'sessionrestore', 04:05:32 INFO - 'sessionrestore_no_auto_restore', 04:05:32 INFO - 'tabpaint']}, 04:05:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['a11yr', 04:05:32 INFO - 'ts_paint', 04:05:32 INFO - 'tpaint', 04:05:32 INFO - 'sessionrestore', 04:05:32 INFO - 'sessionrestore_no_auto_restore', 04:05:32 INFO - 'tabpaint']}, 04:05:32 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:05:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:05:32 INFO - 'bloom_basic_ref']}, 04:05:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['tsvgx', 04:05:32 INFO - 'tsvgr_opacity', 04:05:32 INFO - 'tart', 04:05:32 INFO - 'tscrollx', 04:05:32 INFO - 'cart', 04:05:32 INFO - 'tsvg_static']}, 04:05:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:05:32 INFO - 'tsvgr_opacity', 04:05:32 INFO - 'tart', 04:05:32 INFO - 'tscrollx', 04:05:32 INFO - 'cart', 04:05:32 INFO - 'tsvg_static']}, 04:05:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:05:32 INFO - 'talos_options': ['--disable-e10s'], 04:05:32 INFO - 'tests': ['tp5o']}, 04:05:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:05:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:05:32 INFO - 'talos_options': ['--disable-e10s', 04:05:32 INFO - '--xperf_path', 04:05:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:32 INFO - 'tests': ['tp5n']}, 04:05:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:05:32 INFO - 'talos_options': ['--xperf_path', 04:05:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:32 INFO - 'tests': ['tp5n']}}, 04:05:32 INFO - 'talos.zip': {'path': '', 04:05:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:05:32 INFO - Running post-action listener: _resource_record_post_action 04:05:32 INFO - [mozharness: 2017-05-17 11:05:32.468497Z] Finished populate-webroot step (success) 04:05:32 INFO - [mozharness: 2017-05-17 11:05:32.468653Z] Running create-virtualenv step. 04:05:32 INFO - Running pre-action listener: _resource_record_pre_action 04:05:32 INFO - Running main action method: create_virtualenv 04:05:32 INFO - Creating virtualenv /builds/slave/test/build/venv 04:05:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:05:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:05:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:05:32 INFO - Using real prefix '/tools/python27' 04:05:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:05:33 INFO - Installing distribute.............................................................................................................................................................................................done. 04:05:36 INFO - Installing pip.................done. 04:05:36 INFO - Return code: 0 04:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:05:36 INFO - Reading from file tmpfile_stdout 04:05:36 INFO - Output received: 04:05:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:05:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:36 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:36 INFO - 'HOME': '/Users/cltbld', 04:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:36 INFO - 'LOGNAME': 'cltbld', 04:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:36 INFO - 'MOZ_NO_REMOTE': '1', 04:05:36 INFO - 'NO_EM_RESTART': '1', 04:05:36 INFO - 'PAGER': '/bin/cat', 04:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:36 INFO - 'PWD': '/builds/slave/test', 04:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:36 INFO - 'SHELL': '/bin/bash', 04:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:36 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:36 INFO - 'USER': 'cltbld', 04:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:36 INFO - 'XPC_FLAGS': '0x0', 04:05:36 INFO - 'XPC_SERVICE_NAME': '0', 04:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:05:36 INFO - Cleaning up... 04:05:36 INFO - Return code: 0 04:05:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:36 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:36 INFO - 'HOME': '/Users/cltbld', 04:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:36 INFO - 'LOGNAME': 'cltbld', 04:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:36 INFO - 'MOZ_NO_REMOTE': '1', 04:05:36 INFO - 'NO_EM_RESTART': '1', 04:05:36 INFO - 'PAGER': '/bin/cat', 04:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:36 INFO - 'PWD': '/builds/slave/test', 04:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:36 INFO - 'SHELL': '/bin/bash', 04:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:36 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:36 INFO - 'USER': 'cltbld', 04:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:36 INFO - 'XPC_FLAGS': '0x0', 04:05:36 INFO - 'XPC_SERVICE_NAME': '0', 04:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:36 INFO - Downloading/unpacking psutil>=3.1.1 04:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:05:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:38 INFO - Installing collected packages: psutil 04:05:38 INFO - Running setup.py install for psutil 04:05:39 INFO - building 'psutil._psutil_osx' extension 04:05:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:05:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:05:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:05:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:05:39 INFO - building 'psutil._psutil_posix' extension 04:05:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:05:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:05:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:05:39 INFO - ^ 04:05:39 INFO - 1 warning generated. 04:05:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:05:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:40 INFO - Successfully installed psutil 04:05:40 INFO - Cleaning up... 04:05:40 INFO - Return code: 0 04:05:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:40 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:05:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:40 INFO - 'HOME': '/Users/cltbld', 04:05:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:40 INFO - 'LOGNAME': 'cltbld', 04:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:40 INFO - 'MOZ_NO_REMOTE': '1', 04:05:40 INFO - 'NO_EM_RESTART': '1', 04:05:40 INFO - 'PAGER': '/bin/cat', 04:05:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:40 INFO - 'PWD': '/builds/slave/test', 04:05:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:40 INFO - 'SHELL': '/bin/bash', 04:05:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:40 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:40 INFO - 'USER': 'cltbld', 04:05:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:40 INFO - 'XPC_FLAGS': '0x0', 04:05:40 INFO - 'XPC_SERVICE_NAME': '0', 04:05:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:05:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:05:43 INFO - Installing collected packages: mozsystemmonitor 04:05:43 INFO - Running setup.py install for mozsystemmonitor 04:05:43 INFO - Successfully installed mozsystemmonitor 04:05:43 INFO - Cleaning up... 04:05:43 INFO - Return code: 0 04:05:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:43 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:05:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:43 INFO - 'HOME': '/Users/cltbld', 04:05:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:43 INFO - 'LOGNAME': 'cltbld', 04:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:43 INFO - 'MOZ_NO_REMOTE': '1', 04:05:43 INFO - 'NO_EM_RESTART': '1', 04:05:43 INFO - 'PAGER': '/bin/cat', 04:05:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:43 INFO - 'PWD': '/builds/slave/test', 04:05:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:43 INFO - 'SHELL': '/bin/bash', 04:05:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:43 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:43 INFO - 'USER': 'cltbld', 04:05:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:43 INFO - 'XPC_FLAGS': '0x0', 04:05:43 INFO - 'XPC_SERVICE_NAME': '0', 04:05:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:43 INFO - Downloading/unpacking jsonschema==2.5.1 04:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:05:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:45 INFO - Downloading functools32-3.2.3-2.tar.gz 04:05:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:05:45 INFO - warning: no files found matching '*.txt' 04:05:45 INFO - no previously-included directories found matching 'build' 04:05:45 INFO - no previously-included directories found matching 'dist' 04:05:45 INFO - no previously-included directories found matching '.git*' 04:05:45 INFO - Installing collected packages: jsonschema, functools32 04:05:45 INFO - Running setup.py install for functools32 04:05:45 INFO - warning: no files found matching '*.txt' 04:05:45 INFO - no previously-included directories found matching 'build' 04:05:45 INFO - no previously-included directories found matching 'dist' 04:05:45 INFO - no previously-included directories found matching '.git*' 04:05:45 INFO - Successfully installed jsonschema functools32 04:05:45 INFO - Cleaning up... 04:05:45 INFO - Return code: 0 04:05:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:05: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')]} 04:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05: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')]} 04:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:45 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:45 INFO - 'HOME': '/Users/cltbld', 04:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:45 INFO - 'LOGNAME': 'cltbld', 04:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:45 INFO - 'MOZ_NO_REMOTE': '1', 04:05:45 INFO - 'NO_EM_RESTART': '1', 04:05:45 INFO - 'PAGER': '/bin/cat', 04:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:45 INFO - 'PWD': '/builds/slave/test', 04:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:45 INFO - 'SHELL': '/bin/bash', 04:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:45 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:45 INFO - 'USER': 'cltbld', 04:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:45 INFO - 'XPC_FLAGS': '0x0', 04:05:45 INFO - 'XPC_SERVICE_NAME': '0', 04:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:05:45 INFO - Cleaning up... 04:05:45 INFO - Return code: 0 04:05:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:05: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')]} 04:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05: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')]} 04:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:45 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:45 INFO - 'HOME': '/Users/cltbld', 04:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:45 INFO - 'LOGNAME': 'cltbld', 04:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:45 INFO - 'MOZ_NO_REMOTE': '1', 04:05:45 INFO - 'NO_EM_RESTART': '1', 04:05:45 INFO - 'PAGER': '/bin/cat', 04:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:45 INFO - 'PWD': '/builds/slave/test', 04:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:45 INFO - 'SHELL': '/bin/bash', 04:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:45 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:45 INFO - 'USER': 'cltbld', 04:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:45 INFO - 'XPC_FLAGS': '0x0', 04:05:45 INFO - 'XPC_SERVICE_NAME': '0', 04:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:46 INFO - Downloading/unpacking blobuploader==1.2.4 04:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:47 INFO - Downloading blobuploader-1.2.4.tar.gz 04:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:05:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:05:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:48 INFO - Downloading docopt-0.6.1.tar.gz 04:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:05:48 INFO - Installing collected packages: blobuploader, requests, docopt 04:05:48 INFO - Running setup.py install for blobuploader 04:05:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:05:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:05:48 INFO - Running setup.py install for requests 04:05:49 INFO - Running setup.py install for docopt 04:05:49 INFO - Successfully installed blobuploader requests docopt 04:05:49 INFO - Cleaning up... 04:05:49 INFO - Return code: 0 04:05:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:49 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:49 INFO - 'HOME': '/Users/cltbld', 04:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:49 INFO - 'LOGNAME': 'cltbld', 04:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:49 INFO - 'MOZ_NO_REMOTE': '1', 04:05:49 INFO - 'NO_EM_RESTART': '1', 04:05:49 INFO - 'PAGER': '/bin/cat', 04:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:49 INFO - 'PWD': '/builds/slave/test', 04:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:49 INFO - 'SHELL': '/bin/bash', 04:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:49 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:49 INFO - 'USER': 'cltbld', 04:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:49 INFO - 'XPC_FLAGS': '0x0', 04:05:49 INFO - 'XPC_SERVICE_NAME': '0', 04:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:05:49 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-hdSEzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:05:49 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-HDB4No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:05:49 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-LVgmkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:05:49 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-IIE5U7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:05:49 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-WVrKbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-iqLetl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-aI8fXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-lLgogx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-vzW0dD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-TbjPEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-1s5pPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ukQavJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-mKXtAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:05:50 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ceATF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:51 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-LWrWvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:05:51 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-iIJUGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:05:51 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-1NBPd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:05:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:05:51 INFO - Running setup.py install for manifestparser 04:05:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:05:51 INFO - Running setup.py install for mozcrash 04:05:51 INFO - Running setup.py install for mozdebug 04:05:51 INFO - Running setup.py install for mozdevice 04:05:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:05:51 INFO - Running setup.py install for mozfile 04:05:52 INFO - Running setup.py install for mozhttpd 04:05:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Running setup.py install for mozinfo 04:05:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Running setup.py install for mozInstall 04:05:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Running setup.py install for mozleak 04:05:52 INFO - Running setup.py install for mozlog 04:05:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Running setup.py install for moznetwork 04:05:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Running setup.py install for mozprocess 04:05:53 INFO - Running setup.py install for mozprofile 04:05:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:05:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:05:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:05:53 INFO - Running setup.py install for mozrunner 04:05:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:05:53 INFO - Running setup.py install for mozscreenshot 04:05:53 INFO - Running setup.py install for moztest 04:05:53 INFO - Running setup.py install for mozversion 04:05:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:05:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:05:53 INFO - Cleaning up... 04:05:53 INFO - Return code: 0 04:05:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:53 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:05:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:53 INFO - 'HOME': '/Users/cltbld', 04:05:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:53 INFO - 'LOGNAME': 'cltbld', 04:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:53 INFO - 'MOZ_NO_REMOTE': '1', 04:05:53 INFO - 'NO_EM_RESTART': '1', 04:05:53 INFO - 'PAGER': '/bin/cat', 04:05:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:53 INFO - 'PWD': '/builds/slave/test', 04:05:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:53 INFO - 'SHELL': '/bin/bash', 04:05:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:53 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:53 INFO - 'USER': 'cltbld', 04:05:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:53 INFO - 'XPC_FLAGS': '0x0', 04:05:53 INFO - 'XPC_SERVICE_NAME': '0', 04:05:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:05:54 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-OKMO6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:05:54 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ZqJSmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:05:54 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-7afvZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:05:54 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-8nARq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:05:54 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-EyOIuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:05:54 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-A5seBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:05:54 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-tvck1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:05:54 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-g_0B0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-JWpj2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-oHRu83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Ll_fgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QdZhjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5YxORC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5dzNeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-UmxkMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-bNoQaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:05:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-842wsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:05:55 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:05:55 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:05:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:57 INFO - Downloading blessings-1.6.tar.gz 04:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:05:57 INFO - Installing collected packages: blessings 04:05:57 INFO - Running setup.py install for blessings 04:05:57 INFO - Successfully installed blessings 04:05:57 INFO - Cleaning up... 04:05:57 INFO - Return code: 0 04:05:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:05:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:05:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:05:57 INFO - Reading from file tmpfile_stdout 04:05:57 INFO - Current package versions: 04:05:57 INFO - blessings == 1.6 04:05:57 INFO - blobuploader == 1.2.4 04:05:57 INFO - docopt == 0.6.1 04:05:57 INFO - functools32 == 3.2.3-2 04:05:57 INFO - jsonschema == 2.5.1 04:05:57 INFO - manifestparser == 1.1 04:05:57 INFO - mozInstall == 1.12 04:05:57 INFO - mozcrash == 1.0 04:05:57 INFO - mozdebug == 0.1 04:05:57 INFO - mozdevice == 0.50 04:05:57 INFO - mozfile == 1.2 04:05:57 INFO - mozhttpd == 0.7 04:05:57 INFO - mozinfo == 0.9 04:05:57 INFO - mozleak == 0.1 04:05:57 INFO - mozlog == 3.4 04:05:57 INFO - moznetwork == 0.27 04:05:57 INFO - mozprocess == 0.25 04:05:57 INFO - mozprofile == 0.28 04:05:57 INFO - mozrunner == 6.13 04:05:57 INFO - mozscreenshot == 0.1 04:05:57 INFO - mozsystemmonitor == 0.3 04:05:57 INFO - moztest == 0.8 04:05:57 INFO - mozversion == 1.4 04:05:57 INFO - psutil == 3.1.1 04:05:57 INFO - requests == 1.2.3 04:05:57 INFO - wsgiref == 0.1.2 04:05:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:57 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:05:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:05:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:57 INFO - 'HOME': '/Users/cltbld', 04:05:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:57 INFO - 'LOGNAME': 'cltbld', 04:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:57 INFO - 'MOZ_NO_REMOTE': '1', 04:05:57 INFO - 'NO_EM_RESTART': '1', 04:05:57 INFO - 'PAGER': '/bin/cat', 04:05:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:57 INFO - 'PWD': '/builds/slave/test', 04:05:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:57 INFO - 'SHELL': '/bin/bash', 04:05:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:57 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:57 INFO - 'USER': 'cltbld', 04:05:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:57 INFO - 'XPC_FLAGS': '0x0', 04:05:57 INFO - 'XPC_SERVICE_NAME': '0', 04:05:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:05:58 INFO - Cleaning up... 04:05:58 INFO - Return code: 0 04:05:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:58 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 0x101a0eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95625b98c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, '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.95Ip9MFO4o/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.LagkcXus3a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:05:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:05:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:05:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:05:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:05:58 INFO - 'HOME': '/Users/cltbld', 04:05:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:05:58 INFO - 'LOGNAME': 'cltbld', 04:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:58 INFO - 'MOZ_NO_REMOTE': '1', 04:05:58 INFO - 'NO_EM_RESTART': '1', 04:05:58 INFO - 'PAGER': '/bin/cat', 04:05:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:58 INFO - 'PWD': '/builds/slave/test', 04:05:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:05:58 INFO - 'SHELL': '/bin/bash', 04:05:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:05:58 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:05:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:05:58 INFO - 'USER': 'cltbld', 04:05:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:05:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:58 INFO - 'XPC_FLAGS': '0x0', 04:05:58 INFO - 'XPC_SERVICE_NAME': '0', 04:05:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:05:58 INFO - Cleaning up... 04:05:58 INFO - Return code: 0 04:05:58 INFO - Running post-action listener: _resource_record_post_action 04:05:58 INFO - Running post-action listener: _start_resource_monitoring 04:05:58 INFO - Starting resource monitoring. 04:05:58 INFO - [mozharness: 2017-05-17 11:05:58.540454Z] Finished create-virtualenv step (success) 04:05:58 INFO - [mozharness: 2017-05-17 11:05:58.541379Z] Running install step. 04:05:58 INFO - Running pre-action listener: _resource_record_pre_action 04:05:58 INFO - Running main action method: install 04:05:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:05:58 INFO - Reading from file tmpfile_stdout 04:05:58 INFO - Detecting whether we're running mozinstall >=1.0... 04:05:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:05:58 INFO - Reading from file tmpfile_stdout 04:05:58 INFO - Output received: 04:05:58 INFO - Usage: mozinstall [options] installer 04:05:58 INFO - Options: 04:05:58 INFO - -h, --help show this help message and exit 04:05:58 INFO - -d DEST, --destination=DEST 04:05:58 INFO - Directory to install application into. [default: 04:05:58 INFO - "/builds/slave/test"] 04:05:58 INFO - --app=APP Application being installed. [default: firefox] 04:05:58 INFO - mkdir: /builds/slave/test/build/application 04:05:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:06:19 INFO - Reading from file tmpfile_stdout 04:06:19 INFO - Output received: 04:06:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:06:19 INFO - Running post-action listener: _resource_record_post_action 04:06:19 INFO - [mozharness: 2017-05-17 11:06:19.534130Z] Finished install step (success) 04:06:19 INFO - [mozharness: 2017-05-17 11:06:19.534305Z] Running run-tests step. 04:06:19 INFO - Running pre-action listener: _resource_record_pre_action 04:06:19 INFO - Running main action method: run_tests 04:06:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:06:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:06:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:06:19 INFO - 2017-05-17 04:06:19.548 screenresolution[1621:7272] starting screenresolution argv=screenresolution get 04:06:19 INFO - 2017-05-17 04:06:19.551 screenresolution[1621:7272] Display 0: 1600x1200x32@60 04:06:19 INFO - 2017-05-17 04:06:19.557 screenresolution[1622:7275] starting screenresolution argv=screenresolution list 04:06:19 INFO - Available Modes on Display 0 04:06:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:06:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:06:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:06:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:06:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:06:19 INFO - Intel Iris: 04:06:19 INFO - Chipset Model: Intel Iris 04:06:19 INFO - Type: GPU 04:06:19 INFO - Bus: Built-In 04:06:19 INFO - VRAM (Dynamic, Max): 1536 MB 04:06:19 INFO - Vendor: Intel (0x8086) 04:06:19 INFO - Device ID: 0x0a2e 04:06:19 INFO - Revision ID: 0x0009 04:06:19 INFO - Displays: 04:06:19 INFO - Display: 04:06:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:06:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:06:19 INFO - Display Serial Number: 1600x1200 60 04:06:19 INFO - Main Display: Yes 04:06:19 INFO - Mirror: Off 04:06:19 INFO - Online: Yes 04:06:19 INFO - Rotation: Supported 04:06:19 INFO - Return code: 0 04:06:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:06:19 INFO - Python 2.7.3 04:06:19 INFO - Return code: 0 04:06:19 INFO - grabbing minidump binary from tooltool 04:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:19 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 0x10171ed30>, '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 0x1016d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101713be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:06:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:06:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:06:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:06:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:06:19 INFO - Return code: 0 04:06:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:06:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:06:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:06:19 INFO - ENV: RUST_BACKTRACE is now 1 04:06:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:06:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:06:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 04:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0023 --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 04:06:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.95Ip9MFO4o/Render', 04:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:06:19 INFO - 'HOME': '/Users/cltbld', 04:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:06:19 INFO - 'LOGNAME': 'cltbld', 04:06:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:06:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:19 INFO - 'MOZ_NO_REMOTE': '1', 04:06:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:06:19 INFO - 'NO_EM_RESTART': '1', 04:06:19 INFO - 'PAGER': '/bin/cat', 04:06:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:19 INFO - 'PWD': '/builds/slave/test', 04:06:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:06:19 INFO - 'RUST_BACKTRACE': '1', 04:06:19 INFO - 'SHELL': '/bin/bash', 04:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LagkcXus3a/Listeners', 04:06:19 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 04:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:06:19 INFO - 'USER': 'cltbld', 04:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:19 INFO - 'XPC_FLAGS': '0x0', 04:06:19 INFO - 'XPC_SERVICE_NAME': '0', 04:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:06:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 04:06:20 INFO - mozversion application_buildid: 20170517024756 04:06:20 INFO - mozversion application_changeset: 6e3ca5b38f7173b214b10de49e58cb01890bf39d 04:06:20 INFO - mozversion application_display_name: Nightly 04:06:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:06:20 INFO - mozversion application_name: Firefox 04:06:20 INFO - mozversion application_remotingname: firefox 04:06:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:06:20 INFO - mozversion application_vendor: Mozilla 04:06:20 INFO - mozversion application_version: 55.0a1 04:06:20 INFO - mozversion platform_buildid: 20170517024756 04:06:20 INFO - mozversion platform_changeset: 6e3ca5b38f7173b214b10de49e58cb01890bf39d 04:06:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:06:20 INFO - mozversion platform_version: 55.0a1 04:06:20 INFO - using testdate: 1495019180 04:06:20 INFO - actual date: 1495019180 04:06:20 INFO - starting webserver on 'localhost:49219' 04:06:20 INFO - SUITE-START | Running 2 tests 04:06:20 INFO - TEST-START | dromaeo_css 04:06:20 INFO - operating with platform_type : mac_ 04:06:20 INFO - Initialising browser for dromaeo_css test... 04:06:20 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpOwxRWc/profile http://localhost:49219/getInfo.html) 04:06:20 INFO - PID 1632 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 04:06:22 INFO - PID 1632 | 2017-05-17 04:06:22.584 plugin-container[1634:7456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 04:06:22 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:06:22 INFO - PID 1632 | 2017-05-17 04:06:22.586 plugin-container[1634:7456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e1b, name = 'com.apple.CFPasteboardClient' 04:06:22 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:06:22 INFO - PID 1632 | 2017-05-17 04:06:22.586 plugin-container[1634:7456] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:06:22 INFO - PID 1632 | __metrics Screen width/height:1600/1200 04:06:22 INFO - PID 1632 | colorDepth:24 04:06:22 INFO - PID 1632 | Browser inner width/height: 1024/691 04:06:22 INFO - PID 1632 | __metrics 04:06:23 INFO - TEST-INFO | 1632: exit 0 04:06:23 INFO - Browser initialized. 04:06:23 INFO - Running cycle 1/1 for dromaeo_css test... 04:06:23 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpOwxRWc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:06:25 INFO - PID 1636 | 2017-05-17 04:06:25.464 plugin-container[1637:7603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 04:06:25 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:06:25 INFO - PID 1636 | 2017-05-17 04:06:25.465 plugin-container[1637:7603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 04:06:25 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:06:25 INFO - PID 1636 | 2017-05-17 04:06:25.465 plugin-container[1637:7603] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:06:26 INFO - PID 1636 | 2017-05-17 04:06:26.046 plugin-container[1638:7653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 04:06:26 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:06:26 INFO - PID 1636 | 2017-05-17 04:06:26.047 plugin-container[1638:7653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c03, name = 'com.apple.CFPasteboardClient' 04:06:26 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:06:26 INFO - PID 1636 | 2017-05-17 04:06:26.047 plugin-container[1638:7653] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:06:35 INFO - PID 1636 | RSS: Main: 234577920 04:06:35 INFO - PID 1636 | 04:09:37 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 04:09:39 INFO - PID 1636 | RSS: Main: 257069056 04:09:39 INFO - PID 1636 | 04:12:41 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 04:12:41 INFO - PID 1636 | RSS: Main: 265809920 04:12:41 INFO - PID 1636 | 04:15:43 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 04:15:44 INFO - PID 1636 | RSS: Main: 270176256 04:15:44 INFO - PID 1636 | 04:18:45 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 04:18:46 INFO - PID 1636 | RSS: Main: 268062720 04:18:46 INFO - PID 1636 | 04:21:49 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 04:21:49 INFO - PID 1636 | RSS: Main: 269492224 04:21:49 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 04:24:51 INFO - PID 1636 | RSS: Main: 270790656 04:24:51 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | __start_tp_report 04:24:51 INFO - PID 1636 | _x_x_mozilla_page_load 04:24:51 INFO - PID 1636 | _x_x_mozilla_page_load_details 04:24:51 INFO - PID 1636 | |i|pagename|runs| 04:24:51 INFO - PID 1636 | |0;dojo.html;2919.6496420429553;3360.462326027836;3385.949210761839;3391.4743214801715;3519.366514027475;6066.514678825694;6135.877282454354;6242.906356404652;6251.4998800096;6258.718357673915;4865.245886887529;6026.126211699301;6128.479079278256;6129.32822722196;6137.631742095477;5371.785128594861;5462.918056229159;5473.945260547403;5522.061249587579;5540.113581826909;5507.201455788908;5519.144532597454;5519.613627046108;5525.11598144297;5526.751296191681;5270.209468579706;5459.617826752129;5509.614326997112;5548.140038294071;5582.553395728332;5301.973490132525;5425;5465.890682186374;5503.11950087988;5509.173623956399;4411.580899814512;5270.525652691276;5318.680879147263;5338.092524270884;5343.893122137535;4933.679310844784;6133.487685122345;6221.129041934133;6250.499960003189;6256.311805701369;6546.279909209983;6567.542738456607;6572.671366431659;6598.505112116611;6634.303398143174;6455.193100862392;6584.176977877765;6631.204255489372;6676.065350850885;6682;6325.367463253638;6347.650879201688;6354.332795056545;6366.522510811661;6393.808185754435;6834.316568343225;6857.691403886737;6869.553479023947;6870.175578930461;6887.862242755117;6305.211848518935;6327.462165715966;6398.9040164397575;6488.026795980641;6502.837429064311;6576.736930522821;6649.60102393858;6650.53446258767;6667.599944003376;6698.799036028927;6573.506986975996;6596.505262105361;6606.339366063356;6609.339066093352;6627.403533681992;5180.430152683201;6333.113364129025;6354.936450635527;6493.805185844432;6630.270670226271;5035.16919708244;5297.496737809902;5303.522682958475;5304.814331498379;5349.117395629678;4690.319903613957;4726.196546587088;4738.691985020962;4747.976260118678;4767.594754445911;6142.539309551801;6200.410960958701;6252.749890004349;6315.621062736251;6339.587926784744;6716.1604799400075;6729.091572637729;6751.898721519181;6752.223494298219;6769.086173366631;6265.592736472115;6325.05124231369;6327.367263273636;6330.936690633127;6331.3352098029045;5683.857903552444;5826.388229235869;5834.824955251349;5853.0635098384055;5863.325717542537;6031.396860313934;6698.22970358415;6764.898526522106;6904.723811047502;6904.896426553505;6642.269350371552;6732.697028633625;6750.3924646782025;6762.425193858577;6781.6270105144695;6834.111565496453;6863.691133899087;6886.724531018703;6901.309869013058;6905.965470172617;6354.364563543609;6404.103425520338;6419.454346380423;6448.161738973903;6460.063290822711;6718.025886246369;6732.6297053660555;6741.056252124608;6758.695858686273;6770.390664840188;5207.869803254797;5349.652272602269;6407.231132264158;6522.315156908457;6538.901916471352;6298.653574053471;6336.398042185985;6530.053142294436;6553.279139294578;6557.475401967927;5992;6368.108464815023;6383.712732926937;6393.552451328363;6461.450776684034;6320.209973753281;6573.901391479036;6576.80269591913;6583.80248592543;6595.505337099833;6180.536459765445;6282.528810339334;6394.648294343856;6395.744170233324;6429.871402571829 04:24:51 INFO - PID 1636 | |1;ext.html;699.9930000699993;1100.9834852477209;1101.0750969185883;1112.7162573543744;1241.0815996162842;37623.81188094059;38117.99999999996;38165.85502434924;38186.8090659547;38215;38056.61943380565;38101.80949095251;38373.23253534934;38386.8080659597;38519.807400962956;7498;7699.076110866704;7892.368610511146;7905.486143400689;8427.789305267355;7396.260373962587;7780.338671212954;8422.663093476254;8427.536485493296;8434.40959132861;7069.399101076415;8375.120612335708;8375.246227839492;8458;8463.195996380335;8323.875141872877;8392.118827523114;8413;8418.98972123343;8452.28155606774;8208.958955205186;8283.792905177359;8292.087870334259;8307.792305192359;8321.16788321169;36636.26727465436;36870.44694329587;36890.631093688986;36954;37047;3589.3180295743987;3610.097475631092;3627.4740162676526;3629.2560025195035;3629.6370362963758;30085.548716769266;30119.548206776915;30159.396812063635;30246.09261722152;30297.394052118834;2774.751361887159;2995.9400811983637;3104.6895310469;3108.5026395776567;3109.8911538096286;232393.9999999983;249391;300124;302256;309764;42738.78630606827;43496.13007739827;45892;46034.539654603024;47176.52823471721;48492.3027694029;48583.75708121437;48592;48600.27099593509;48657;13845.723085538233;13963.511277105252;13965.371558279901;13976.790348144787;14017.088889222168;11037.006669399792;11099.556017759362;11178.832317515244;11182.832257516144;11202.767695553483;13122.737545249232;13236.801447978096;13265.867341326462;13269.800952985714;13373.331333433289;5666.490015898589;5713.74288157034;5725.828225153252;5726.1410788381245;5743.770249190069;1023.4217667018111;1035.6168217759478;1040.968770936858;1065.7708592652616;1075.9677209683723;22262.554748905255;23314.184003560134;24545.545047287207;24571.63142552863;24712.629310560354;1112.57165991094;1115.7322242661864;1126.1666366888608;1152.8904754048351;1154.8440960470398;10420.843687344695;12418.75162496745;12537.185082969578;12576.559820406243;12627.368631568384;316329;317105;319251;319441;319868;42127.94680133021;51120.21079262208;51253.97492050213;51309.48690513047;51596.22605660918;1428.250168661461;1530.8469153084602;1543.0664448008793;1549.6590750034975;1564.6323114068412;22135.66796498086;23801.404964876016;23835.52328953446;23876.40308992289;23959;36891.44662830006;36998.81500592588;37002.259954801295;37066.444003339435;37158.628413715516;229253;232529;232824;232832;233072.83463582574;33812.661873381934;34125.82937085299;34345.82827085849;35558;35720.464193037646;42106.578934210265;44405.11189776122;44408.11183776371;44446;44477.777611111735;29324.56013159847;29952.400951981275;30370.240743981576;31007.84496077505;31032.689673103883;30268.84865575746;30612.846935765177;31004.68995310018;31166.844165779024;31270.218244544067;35414;35882;36850.26299474049;37027.07432314214;37124.257514849014;30280;31079.533806993364;31301.904433344724;31305.686943130277;31344;23335.766642333358;24432.877835610707;24593.87703061473;24695.62956555689;24702.25893223206 04:24:51 INFO - PID 1636 | |2;jquery.html;428.9077848262623;435.32833917755823;435.36219438522556;446.3438745044784;448.75542829158104;21699.674504882438;21766;22018.559628807434;22486.10055597774;22577.322680319576;21151.47121321964;21878.671819922714;21883.6717449238;22111.778882211212;22371.44071398212;5892.558058145635;6082.847928801771;6131.172291740602;6140.293866205398;6146.692665366736;4989.176785830334;5820.796272130477;5847.210626565423;6067.484263837579;6161.753529858823;5164.586833053366;5918.88162236755;5996.040633498641;6065.878682426349;6071.78748743794;5971.552133589998;6012.158297838328;6096.603720758181;6107;6107.083937409379;4708.246680531099;5293.100172970604;5347.6256662033675;5386.48828361305;5399.136138217906;26537.601935970975;28003.85998070017;28059.158225253275;28248.435031299257;28416.715832841615;2156.9029393677324;2222.855514391559;2226.243077353691;2228.119892642397;2228.754836967932;17821.554461138447;17956.461306160836;18099.457016289532;18299.08504574766;18556.628867422576;2116.121809449077;2125.4048866317457;2168.316980151263;2169.143188440573;2169.3383518026994;138401;164556;164998;167159;168842;12398;12442.75114497705;12562.120651554485;12579.433925473375;12822.294773787346;16249.837501625068;16499.6700065998;16507.33970641185;16521.6695666086;16531.586710332096;8250;8629.352798540134;8813.867791983126;8953.059928707522;9111.453312801254;8235;8393.034800997963;8398.580070996426;8550.273226775793;8563.486190828446;6450.516211284173;7013.859722805515;7697.846043079219;7965.8805117923275;8026.398020148512;2136.989315053446;2154.2029449103934;2164;2164.296603603835;2366.6450032495263;954.160338901762;960.2366118935463;969.5588507229194;992.0872797026752;1013.3008224325192;19126.521836954187;19325.937073461093;21163.365099047052;21622.675659865115;21815.23646672359;1000.5897581991348;1002.4737013068197;1021.4637315409469;1025.7281820317621;1032.318669678024;10498.475076246157;11147;11210.55157793679;11223.775524489465;11274.154438417152;513666;546836;548098;557647.2117639385;560980;124245;126310.36844815717;131390.68609313975;131509.34245328713;134660.6533934648;1197.9940100299443;1230.9630711078682;1250.6998320403038;1254.5295514182183;1260.7478504299177;22030.339089827332;22240.110395583997;22755.31734047981;22786.974586143995;23375.649365259877;40965.97585060397;54862.72568637131;56524;56526.71736641291;58314;78089.38302161865;79180;80187.59906200432;80601.59699201466;80709.59645201972;8245.299149572353;8562.914370856211;8588.441751286146;8631.91368086311;8919.331050171133;13910.37403316833;14010.299485025911;14051.227182505061;14392.568222953329;14438.278086095863;8971;10628.52171652262;11447.969682728595;11905.940470297593;12030.699232519257;10120.89879101229;11171.72070698239;11301.43492825339;11313.660590182308;11633;11961.457193808017;12030.699232519257;12086.75826483483;12155.02759779198;12318.384080796104;7898.921010790048;8092.028956525254;8614.91385086141;8939.329550283624;9010.414323068979;11543.4805433754;11550.82673759911;11646.359450230319;11693.88306116928;11864.99147572466 04:24:51 INFO - PID 1636 | |3;mootools.html;1412.9929350353248;1429.8141241638587;1484.2430360516141;1485.9227060381218;1495.080525476832;10131.290809643328;10339.22455815815;11431.14266430018;11839.644810655673;11917.880821191797;11310.755816860139;11663.708407289821;11671.1830171888;11673.766524669501;11677.299362038284;2180.760116387196;2495.9750402496015;2555.961660575093;2561.065211197909;2562.423454722691;2499.3126890105186;2556.654851595039;2558;2559.6672432583737;2563.3719738664054;2527.8736063196857;2537.8223524353302;2541.2884392370165;2542.478791847676;2546.9235922922344;2487.7387874273213;2495.1765917247358;2499.512595043967;2503.161440917295;2515.195137555983;2078.688196770481;2467.247489515697;2485.155047283932;2489.825712200147;2500.4249022724875;11415.771684566345;11459.083273338112;11464.31214127155;11499.252548584398;11585.304881707125;6460.93539064608;6722.4285935695525;6726.3609957054005;6726.730930762764;6733.124693789825;4070.61329173728;4250.766207858566;4304.117655880537;4360.367746676746;4398.8020539075815;1166.9824952625718;1168.6669299249672;1275.8787915148046;1279.5969269680113;1282.5126451948292;16308.103054332007;16344.75482867758;16429.342826286935;16438.424655137133;16459.423920162735;5782.576329108236;6036.5170786336985;6122.816315510542;6164.291106522717;6181.567290289637;13772.10481318711;13782.517611883535;13784.897208223318;13813.792793108112;13829;6781.9827025945515;6797.9660101700165;6804.251532331439;6811.693473793691;6823.761168359183;6071.696415179224;6253.74985000604;6288.496920246369;6316.368363163647;6319.05214217871;4220.556841531657;4356.215881141425;4398.032432864765;4405.537418571068;4421.358902959053;1258.1758947889148;1260.7793636113643;1266.949322027127;1269.7904845700357;1271.7265787855656;667.6895243711697;791.6516732637621;818.1041759273586;824.6371596497522;825.1377310710322;5158.432572417031;5614.578906582023;5738.7948530809;6157.322694503601;6340.524460665469;586.4839390176442;589.5195415736162;601.8736065426222;603.4177019176516;605.4883623338272;3759.812009399574;3790.753601015925;3883.3204189266767;3906.882793516199;4319.8488052918;12771.808422873664;13912.860871391358;14401.855981440052;14948.701025979637;14955.177465239298;14299.07056041354;15010.099394036393;15025.849741502663;15026.248687565578;15049.774253386207;8155.143709910492;8542.78643033929;8561.957190214009;8570.74287771368;8584.613692383859;7025.789226323219;7042.330978557029;7178.066851309296;7195.6761945713615;7246.89129663066;12995.48018079304;13068.150570212905;13137.540186093442;13296.135751176143;13505.729885402434;13459.192448453125;13583.117097388638;13714.17714937107;13721.862781372058;13997.44010239579;8617.48295102296;9137.314701397288;9369.718908432758;9526.857097143687;9541.998090200428;11067;11113.833292500458;11165.553377864795;11176.944115279372;11191.72020699489;8384.916150838413;8512.702055428277;8580.184882436159;8600.354973376896;8633.093525180017;8344.958275208584;8376.413651044368;8439.70461033861;8465.111163327761;8496.192861678132;8065.516069035877;8134.389920756085;8147.633356498854;8166.42835001568;8210.83578328442;6006.699665016644;6123.203983482119;6190.566660333733;6196.81409557714;6263.872502949513;5998.8500287491415;6168.568200226121;6262.248530176225;6287.874242515032;6289.465395441439 04:24:51 INFO - PID 1636 | |4;prototype.html;339.80800847521147;355.52714889197364;402.6175133623059;404.07466900797175;406.6096547314577;5326.973365133169;6063.181470501481;6307.653079080649;6316.473410635748;6353.428191462767;5754.625949313302;6211.254649442073;6268.811935641938;6298.748050077991;6329.303776584584;4785.258284965827;4791.089692958344;4791.137595232856;4797.496262892399;4810.422749270093;3774.7168962327796;4367.388565600819;4522.09558088382;4529.818807247706;4812.494688057756;4405.096955124208;4456.22016147173;4486.528914463983;4494.730316181023;4498.955010449887;4158.708890377675;4506.166359223538;4510.661700372485;4514.706544074657;4515.14212299662;3807.58116607173;3833.11838277195;3837.9232415351535;3883.9513331400644;3891.1439483313625;5018.824341148043;6015.428534289235;6016.96991515044;6088.573799834014;6104.694765261719;5404.243405923174;5410.594205434608;5424.457554244584;5442.4489020628935;5495.835124946258;6502.739890404426;6604.928708150733;6634.071230027752;6660;6680.498962577826;3204.6314673812344;3442.1910850950007;3513.683768460851;4066.3900414937702;4309.504406993205;5653.925754106747;6711.295313992009;6998.860022799567;7091.971664108727;7154.928450715478;4380.364847097217;4434.933475997863;4441.222786012435;4445.377647129404;4446.777661116996;4492.326151077365;4505.234110201273;4512.593866552026;4516.480604730433;4519.525449827786;5202.92195617066;5302.39022512416;5374.769470669628;5413.539849112791;5418.241446197536;5570;6587.002913674362;6673.666316684146;6692.196936367667;6738.59568425896;1513.7435928179584;1543.1204213598141;1544.3745283160338;1546.636540413002;1552.8447155284607;879.7272845417813;940.849464085743;942.5475771629519;979;998.1415982255255;5674.858128546737;6170.500947116425;6214.906776398358;6227.626342419469;6279.335975966373;6954.269801670853;6976.232614412411;7003.579785212802;7026.683799229047;7227.566346019255;5937.465628093492;6132.41742034506;6202.751889924352;6221.751129954841;6245.500359971192;3059.128148477673;3561.756093660977;3574.37448446526;3583.175869550018;3604.6395360464267;6422.710978006001;6462.352100214415;6865.244823069458;7080.681369338392;7092.716291348288;7040.295970402919;7044.436445084381;7049.753258635923;7086.893696594555;7089.7873063808165;5668.036433806263;6239.126522286792;6962.303769623099;7182.7126914923865;7263.201047884834;5849;6818.454523638196;7062.1878483975;7088.468364872552;7099.574025558483;5533.142362933751;6098.146259523582;6723.932760672331;6873.828154296182;6966.024756534191;5629.746661400329;6623.536352455475;7257.2742725727;7264.200937896721;7291.192890736998;5048.116579598629;5097.776533631827;5098;5138.640295179302;5151.7166555839785;6411.8397040072505;6819.420349270367;6962.651867406508;7018.5788852669;7074.327938846007;5420.4850539198715;5812.622179558315;6173.783917562864;6735.562188457735;6788.9660551696925;6668.6665666717445;6739.865202695821;6752.729890804313;6807.897881531682;6821.045053692388;5678.6876721779045;6654.234763002123;6678;6682.231543372574;6696.430803381572;5702.885942281214;6580.144581204413;6753.2233793112455;6767;6782.914733642593;5984.551158663031;6020.518358531394;6156.6921653918025;6639.966800166161;6711.563748356341 04:24:51 INFO - PID 1636 | |5;yui.html;770.4106358635643;820.7619790260825;848.0374774630792;851.4337965253108;855.8545047341951;16432.096234707085;16915.238814253356;16949.745753813688;18443.815561844378;19097.809021909776;17670.82329176708;18208.908955455237;18333.26666933321;18351.35770248038;18695.317421432068;986.3588667366233;1003.2074661017787;1004.9598016079348;1006.3257617396362;1006.7432804634808;983.8130755156533;1005.6480231918806;1011.3780025284434;1011.6560369474378;1014.614446510325;1044.858944042556;1057.9629712960048;1063.2769716592713;1064.5741703318656;1065.137238836541;1022.6676330192718;1031.494567661844;1051.9000694933968;1055.3931489393592;1056.4400867540216;759.2483441393036;771.0785611194568;772.474717192309;775.3796962430033;779.1857508903153;16757.581060473585;16873.40943066999;16877.324907003705;16887.240074196692;16889.324427022904;16851.241694123793;16940.23768930401;17008.91495542527;17248.655026899392;17307.740383894252;13694.452221911111;13698.38357273925;13914.443422263199;13991.950603704763;14028.298585070706;3523.841427135785;3573.160307327776;3574.338747331726;3575.6781889630056;3579.8210089495683;108802;111748.88251117547;117813;124465.37767311106;125421.11868321983;13505.52730654442;13793.310334483236;13799.103058301178;13812.930935345259;13880.375383107785;24959.37601559939;25138.497230055298;25417.110401135873;25845.741542584332;25957.870210648827;6911.412529934912;7029.3673569379;7048.894266586005;7049.506534542533;7053;3172.6192856856865;3212.726918211931;3214.2125179331215;3217.6299725531862;3220.581324427856;4268.125034367947;4294.076773493652;4378.255696531597;4406.294992801137;4408.272635015187;916.7295647783919;934.0939288889766;946.3091942881734;949.6011675096476;951.7620594851287;624.7719582352499;666.3436515032689;676.7969609117246;677.2719326723693;691.6507163882208;10298.536565854554;11214.551417943354;11387;12011.339376334385;13119.672008199872;610.6671863834221;657.0735263278754;660.6993817812884;665.1519312876121;694.4201591671011;5267.446918073547;5327.600429967768;5397.568194544427;5610.1584762285165;5628.943710562924;75674.62162689152;80671.59664201642;101710.49144754228;104503.4774826121;106681.46659266653;79875.41495020635;80615.19384806076;83898.58050709829;87970;88362.55818720994;55893.7205313979;73240.26759732334;73633;73973;74088;12066.939665301968;12353.38233088324;12357.196782209128;12424.503019879103;12460.00319974418;38449.4232586517;38476.2304753909;38550.6144938547;38914.80542597269;39364.803175983936;47405.28892066553;59711.20866373842;65655;70143;72173;15292.617684557976;16004.91997540005;16141.59646008859;16376.918115409348;16587.84358033448;17709.468715938543;18331.35840245585;18508.259669613064;18578.81421185771;18618.720719188954;16267.593310166867;16726.916365418096;16965.91517042407;17060.061696606805;17170;17600.207990640196;17758.11209439549;18348.715589909112;18655.72016419782;18737.06314684233;14587.489437869624;17121.74317385265;17566.560835979202;18224.635507290044;18365.632687346446;9556.948735639213;11439.771204575696;11502.022328102204;11561.684235697307;11916;12042.458089386173;12614.432350544064;13222.669433264246;13682.794758078438;13835.169889806644 04:24:51 INFO - PID 1636 | __end_tp_report 04:24:51 INFO - PID 1636 | __start_cc_report 04:24:51 INFO - PID 1636 | _x_x_mozilla_cycle_collect,353 04:24:51 INFO - PID 1636 | __end_cc_report 04:24:51 INFO - PID 1636 | __startTimestamp1495020291643__endTimestamp 04:24:51 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | ------- Summary: start ------- 04:24:51 INFO - PID 1636 | Number of tests: 6 04:24:51 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6085.73 Median:6336.40 stddev:762.46 (12.0%) stddev-sans-first:723.22 04:24:51 INFO - PID 1636 | Values: 2919.6 3360.5 3385.9 3391.5 3519.4 6066.5 6135.9 6242.9 6251.5 6258.7 4865.2 6026.1 6128.5 6129.3 6137.6 5371.8 5462.9 5473.9 5522.1 5540.1 5507.2 5519.1 5519.6 5525.1 5526.8 5270.2 5459.6 5509.6 5548.1 5582.6 5302.0 5425.0 5465.9 5503.1 5509.2 4411.6 5270.5 5318.7 5338.1 5343.9 4933.7 6133.5 6221.1 6250.5 6256.3 6546.3 6567.5 6572.7 6598.5 6634.3 6455.2 6584.2 6631.2 6676.1 6682.0 6325.4 6347.7 6354.3 6366.5 6393.8 6834.3 6857.7 6869.6 6870.2 6887.9 6305.2 6327.5 6398.9 6488.0 6502.8 6576.7 6649.6 6650.5 6667.6 6698.8 6573.5 6596.5 6606.3 6609.3 6627.4 5180.4 6333.1 6354.9 6493.8 6630.3 5035.2 5297.5 5303.5 5304.8 5349.1 4690.3 4726.2 4738.7 4748.0 4767.6 6142.5 6200.4 6252.7 6315.6 6339.6 6716.2 6729.1 6751.9 6752.2 6769.1 6265.6 6325.1 6327.4 6330.9 6331.3 5683.9 5826.4 5834.8 5853.1 5863.3 6031.4 6698.2 6764.9 6904.7 6904.9 6642.3 6732.7 6750.4 6762.4 6781.6 6834.1 6863.7 6886.7 6901.3 6906.0 6354.4 6404.1 6419.5 6448.2 6460.1 6718.0 6732.6 6741.1 6758.7 6770.4 5207.9 5349.7 6407.2 6522.3 6538.9 6298.7 6336.4 6530.1 6553.3 6557.5 5992.0 6368.1 6383.7 6393.6 6461.5 6320.2 6573.9 6576.8 6583.8 6595.5 6180.5 6282.5 6394.6 6395.7 6429.9 04:24:51 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:42788.71 Median:318178.00 stddev:73144.74 (23.0%) stddev-sans-first:73281.66 04:24:51 INFO - PID 1636 | Values: 700.0 1101.0 1101.1 1112.7 1241.1 37623.8 38118.0 38165.9 38186.8 38215.0 38056.6 38101.8 38373.2 38386.8 38519.8 7498.0 7699.1 7892.4 7905.5 8427.8 7396.3 7780.3 8422.7 8427.5 8434.4 7069.4 8375.1 8375.2 8458.0 8463.2 8323.9 8392.1 8413.0 8419.0 8452.3 8209.0 8283.8 8292.1 8307.8 8321.2 36636.3 36870.4 36890.6 36954.0 37047.0 3589.3 3610.1 3627.5 3629.3 3629.6 30085.5 30119.5 30159.4 30246.1 30297.4 2774.8 2995.9 3104.7 3108.5 3109.9 232394.0 249391.0 300124.0 302256.0 309764.0 42738.8 43496.1 45892.0 46034.5 47176.5 48492.3 48583.8 48592.0 48600.3 48657.0 13845.7 13963.5 13965.4 13976.8 14017.1 11037.0 11099.6 11178.8 11182.8 11202.8 13122.7 13236.8 13265.9 13269.8 13373.3 5666.5 5713.7 5725.8 5726.1 5743.8 1023.4 1035.6 1041.0 1065.8 1076.0 22262.6 23314.2 24545.5 24571.6 24712.6 1112.6 1115.7 1126.2 1152.9 1154.8 10420.8 12418.8 12537.2 12576.6 12627.4 316329.0 317105.0 319251.0 319441.0 319868.0 42127.9 51120.2 51254.0 51309.5 51596.2 1428.3 1530.8 1543.1 1549.7 1564.6 22135.7 23801.4 23835.5 23876.4 23959.0 36891.4 36998.8 37002.3 37066.4 37158.6 229253.0 232529.0 232824.0 232832.0 233072.8 33812.7 34125.8 34345.8 35558.0 35720.5 42106.6 44405.1 44408.1 44446.0 44477.8 29324.6 29952.4 30370.2 31007.8 31032.7 30268.8 30612.8 31004.7 31166.8 31270.2 35414.0 35882.0 36850.3 37027.1 37124.3 30280.0 31079.5 31301.9 31305.7 31344.0 23335.8 24432.9 24593.9 24695.6 24702.3 04:24:51 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:35648.53 Median:2227.18 stddev:92893.90 (4170.9%) stddev-sans-first:93116.85 04:24:51 INFO - PID 1636 | Values: 428.9 435.3 435.4 446.3 448.8 21699.7 21766.0 22018.6 22486.1 22577.3 21151.5 21878.7 21883.7 22111.8 22371.4 5892.6 6082.8 6131.2 6140.3 6146.7 4989.2 5820.8 5847.2 6067.5 6161.8 5164.6 5918.9 5996.0 6065.9 6071.8 5971.6 6012.2 6096.6 6107.0 6107.1 4708.2 5293.1 5347.6 5386.5 5399.1 26537.6 28003.9 28059.2 28248.4 28416.7 2156.9 2222.9 2226.2 2228.1 2228.8 17821.6 17956.5 18099.5 18299.1 18556.6 2116.1 2125.4 2168.3 2169.1 2169.3 138401.0 164556.0 164998.0 167159.0 168842.0 12398.0 12442.8 12562.1 12579.4 12822.3 16249.8 16499.7 16507.3 16521.7 16531.6 8250.0 8629.4 8813.9 8953.1 9111.5 8235.0 8393.0 8398.6 8550.3 8563.5 6450.5 7013.9 7697.8 7965.9 8026.4 2137.0 2154.2 2164.0 2164.3 2366.6 954.2 960.2 969.6 992.1 1013.3 19126.5 19325.9 21163.4 21622.7 21815.2 1000.6 1002.5 1021.5 1025.7 1032.3 10498.5 11147.0 11210.6 11223.8 11274.2 513666.0 546836.0 548098.0 557647.2 560980.0 124245.0 126310.4 131390.7 131509.3 134660.7 1198.0 1231.0 1250.7 1254.5 1260.7 22030.3 22240.1 22755.3 22787.0 23375.6 40966.0 54862.7 56524.0 56526.7 58314.0 78089.4 79180.0 80187.6 80601.6 80709.6 8245.3 8562.9 8588.4 8631.9 8919.3 13910.4 14010.3 14051.2 14392.6 14438.3 8971.0 10628.5 11448.0 11905.9 12030.7 10120.9 11171.7 11301.4 11313.7 11633.0 11961.5 12030.7 12086.8 12155.0 12318.4 7898.9 8092.0 8614.9 8939.3 9010.4 11543.5 11550.8 11646.4 11693.9 11865.0 04:24:51 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7106.11 Median:3775.28 stddev:4509.00 (119.4%) stddev-sans-first:4501.35 04:24:51 INFO - PID 1636 | Values: 1413.0 1429.8 1484.2 1485.9 1495.1 10131.3 10339.2 11431.1 11839.6 11917.9 11310.8 11663.7 11671.2 11673.8 11677.3 2180.8 2496.0 2556.0 2561.1 2562.4 2499.3 2556.7 2558.0 2559.7 2563.4 2527.9 2537.8 2541.3 2542.5 2546.9 2487.7 2495.2 2499.5 2503.2 2515.2 2078.7 2467.2 2485.2 2489.8 2500.4 11415.8 11459.1 11464.3 11499.3 11585.3 6460.9 6722.4 6726.4 6726.7 6733.1 4070.6 4250.8 4304.1 4360.4 4398.8 1167.0 1168.7 1275.9 1279.6 1282.5 16308.1 16344.8 16429.3 16438.4 16459.4 5782.6 6036.5 6122.8 6164.3 6181.6 13772.1 13782.5 13784.9 13813.8 13829.0 6782.0 6798.0 6804.3 6811.7 6823.8 6071.7 6253.7 6288.5 6316.4 6319.1 4220.6 4356.2 4398.0 4405.5 4421.4 1258.2 1260.8 1266.9 1269.8 1271.7 667.7 791.7 818.1 824.6 825.1 5158.4 5614.6 5738.8 6157.3 6340.5 586.5 589.5 601.9 603.4 605.5 3759.8 3790.8 3883.3 3906.9 4319.8 12771.8 13912.9 14401.9 14948.7 14955.2 14299.1 15010.1 15025.8 15026.2 15049.8 8155.1 8542.8 8562.0 8570.7 8584.6 7025.8 7042.3 7178.1 7195.7 7246.9 12995.5 13068.2 13137.5 13296.1 13505.7 13459.2 13583.1 13714.2 13721.9 13997.4 8617.5 9137.3 9369.7 9526.9 9542.0 11067.0 11113.8 11165.6 11176.9 11191.7 8384.9 8512.7 8580.2 8600.4 8633.1 8345.0 8376.4 8439.7 8465.1 8496.2 8065.5 8134.4 8147.6 8166.4 8210.8 6006.7 6123.2 6190.6 6196.8 6263.9 5998.9 6168.6 6262.2 6287.9 6289.5 04:24:51 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5379.57 Median:6093.36 stddev:1718.52 (28.2%) stddev-sans-first:1681.19 04:24:51 INFO - PID 1636 | Values: 339.8 355.5 402.6 404.1 406.6 5327.0 6063.2 6307.7 6316.5 6353.4 5754.6 6211.3 6268.8 6298.7 6329.3 4785.3 4791.1 4791.1 4797.5 4810.4 3774.7 4367.4 4522.1 4529.8 4812.5 4405.1 4456.2 4486.5 4494.7 4499.0 4158.7 4506.2 4510.7 4514.7 4515.1 3807.6 3833.1 3837.9 3884.0 3891.1 5018.8 6015.4 6017.0 6088.6 6104.7 5404.2 5410.6 5424.5 5442.4 5495.8 6502.7 6604.9 6634.1 6660.0 6680.5 3204.6 3442.2 3513.7 4066.4 4309.5 5653.9 6711.3 6998.9 7092.0 7154.9 4380.4 4434.9 4441.2 4445.4 4446.8 4492.3 4505.2 4512.6 4516.5 4519.5 5202.9 5302.4 5374.8 5413.5 5418.2 5570.0 6587.0 6673.7 6692.2 6738.6 1513.7 1543.1 1544.4 1546.6 1552.8 879.7 940.8 942.5 979.0 998.1 5674.9 6170.5 6214.9 6227.6 6279.3 6954.3 6976.2 7003.6 7026.7 7227.6 5937.5 6132.4 6202.8 6221.8 6245.5 3059.1 3561.8 3574.4 3583.2 3604.6 6422.7 6462.4 6865.2 7080.7 7092.7 7040.3 7044.4 7049.8 7086.9 7089.8 5668.0 6239.1 6962.3 7182.7 7263.2 5849.0 6818.5 7062.2 7088.5 7099.6 5533.1 6098.1 6723.9 6873.8 6966.0 5629.7 6623.5 7257.3 7264.2 7291.2 5048.1 5097.8 5098.0 5138.6 5151.7 6411.8 6819.4 6962.7 7018.6 7074.3 5420.5 5812.6 6173.8 6735.6 6789.0 6668.7 6739.9 6752.7 6807.9 6821.0 5678.7 6654.2 6678.0 6682.2 6696.4 5702.9 6580.1 6753.2 6767.0 6782.9 5984.6 6020.5 6156.7 6640.0 6711.6 04:24:51 INFO - PID 1636 | 04:24:51 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:21023.64 Median:18358.50 stddev:28480.94 (155.1%) stddev-sans-first:28520.23 04:24:51 INFO - PID 1636 | Values: 770.4 820.8 848.0 851.4 855.9 16432.1 16915.2 16949.7 18443.8 19097.8 17670.8 18208.9 18333.3 18351.4 18695.3 986.4 1003.2 1005.0 1006.3 1006.7 983.8 1005.6 1011.4 1011.7 1014.6 1044.9 1058.0 1063.3 1064.6 1065.1 1022.7 1031.5 1051.9 1055.4 1056.4 759.2 771.1 772.5 775.4 779.2 16757.6 16873.4 16877.3 16887.2 16889.3 16851.2 16940.2 17008.9 17248.7 17307.7 13694.5 13698.4 13914.4 13992.0 14028.3 3523.8 3573.2 3574.3 3575.7 3579.8 108802.0 111748.9 117813.0 124465.4 125421.1 13505.5 13793.3 13799.1 13812.9 13880.4 24959.4 25138.5 25417.1 25845.7 25957.9 6911.4 7029.4 7048.9 7049.5 7053.0 3172.6 3212.7 3214.2 3217.6 3220.6 4268.1 4294.1 4378.3 4406.3 4408.3 916.7 934.1 946.3 949.6 951.8 624.8 666.3 676.8 677.3 691.7 10298.5 11214.6 11387.0 12011.3 13119.7 610.7 657.1 660.7 665.2 694.4 5267.4 5327.6 5397.6 5610.2 5628.9 75674.6 80671.6 101710.5 104503.5 106681.5 79875.4 80615.2 83898.6 87970.0 88362.6 55893.7 73240.3 73633.0 73973.0 74088.0 12066.9 12353.4 12357.2 12424.5 12460.0 38449.4 38476.2 38550.6 38914.8 39364.8 47405.3 59711.2 65655.0 70143.0 72173.0 15292.6 16004.9 16141.6 16376.9 16587.8 17709.5 18331.4 18508.3 18578.8 18618.7 16267.6 16726.9 16965.9 17060.1 17170.0 17600.2 17758.1 18348.7 18655.7 18737.1 14587.5 17121.7 17566.6 18224.6 18365.6 9556.9 11439.8 11502.0 11561.7 11916.0 12042.5 12614.4 13222.7 13682.8 13835.2 04:24:51 INFO - PID 1636 | -------- Summary: end -------- 04:24:51 INFO - PID 1636 | 04:24:52 INFO - TEST-INFO | 1636: exit 0 04:24:52 INFO - TEST-OK | dromaeo_css | took 1112150ms 04:24:52 INFO - TEST-START | kraken 04:24:52 INFO - operating with platform_type : mac_ 04:24:52 INFO - Initialising browser for kraken test... 04:24:52 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpAcFqLr/profile http://localhost:49219/getInfo.html) 04:24:52 INFO - PID 1649 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 04:24:54 INFO - PID 1649 | 2017-05-17 04:24:54.753 plugin-container[1650:10035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 04:24:54 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:54 INFO - PID 1649 | 2017-05-17 04:24:54.757 plugin-container[1650:10035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 04:24:54 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:54 INFO - PID 1649 | 2017-05-17 04:24:54.757 plugin-container[1650:10035] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:24:54 INFO - PID 1649 | __metrics Screen width/height:1600/1200 04:24:54 INFO - PID 1649 | colorDepth:24 04:24:54 INFO - PID 1649 | Browser inner width/height: 1024/691 04:24:54 INFO - PID 1649 | __metrics 04:24:55 INFO - PID 1649 | 04:24:55 INFO - PID 1649 | ###!!! [Child][MessageChannel] Error: (msgtype=0x7C0029,name=PHal::Msg_EnableWakeLockNotifications) Channel closing: too late to send/recv, messages will be lost 04:24:55 INFO - PID 1649 | 04:24:55 INFO - PID 1649 | 2017-05-17 04:24:55.395 plugin-container[1651:10093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 04:24:55 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:55 INFO - PID 1649 | 2017-05-17 04:24:55.395 plugin-container[1651:10093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 04:24:55 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:55 INFO - PID 1649 | 2017-05-17 04:24:55.395 plugin-container[1651:10093] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:24:55 INFO - TEST-INFO | 1649: exit 0 04:24:55 INFO - Browser initialized. 04:24:55 INFO - Running cycle 1/1 for kraken test... 04:24:55 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpAcFqLr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:24:57 INFO - PID 1652 | 2017-05-17 04:24:57.560 plugin-container[1653:10191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 04:24:57 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:57 INFO - PID 1652 | 2017-05-17 04:24:57.560 plugin-container[1653:10191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 04:24:57 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:57 INFO - PID 1652 | 2017-05-17 04:24:57.561 plugin-container[1653:10191] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:24:58 INFO - PID 1652 | 2017-05-17 04:24:58.332 plugin-container[1654:10239] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 04:24:58 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:58 INFO - PID 1652 | 2017-05-17 04:24:58.333 plugin-container[1654:10239] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 04:24:58 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:58 INFO - PID 1652 | 2017-05-17 04:24:58.333 plugin-container[1654:10239] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:25:07 INFO - PID 1652 | RSS: Main: 238821376 04:25:07 INFO - PID 1652 | 04:25:10 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 04:25:11 INFO - PID 1652 | RSS: Main: 251244544 04:25:11 INFO - PID 1652 | 04:25:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 04:25:14 INFO - PID 1652 | RSS: Main: 260120576 04:25:14 INFO - PID 1652 | 04:25:18 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 04:25:19 INFO - PID 1652 | RSS: Main: 261988352 04:25:19 INFO - PID 1652 | 04:25:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 04:25:21 INFO - PID 1652 | RSS: Main: 263815168 04:25:21 INFO - PID 1652 | 04:25:23 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:25:24 INFO - PID 1652 | RSS: Main: 264380416 04:25:24 INFO - PID 1652 | 04:25:27 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 04:25:28 INFO - PID 1652 | RSS: Main: 270434304 04:25:28 INFO - PID 1652 | 04:25:31 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 04:25:31 INFO - PID 1652 | RSS: Main: 269144064 04:25:31 INFO - PID 1652 | 04:25:34 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 04:25:35 INFO - PID 1652 | RSS: Main: 268824576 04:25:35 INFO - PID 1652 | 04:25:36 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:25:37 INFO - PID 1652 | RSS: Main: 268546048 04:25:37 INFO - PID 1652 | 04:25:39 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:25:39 INFO - PID 1652 | RSS: Main: 268595200 04:25:39 INFO - PID 1652 | 04:25:41 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:25:42 INFO - PID 1652 | RSS: Main: 268292096 04:25:42 INFO - PID 1652 | 04:25:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:25:46 INFO - PID 1652 | RSS: Main: 268476416 04:25:46 INFO - PID 1652 | 04:25:49 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:25:49 INFO - PID 1652 | RSS: Main: 268541952 04:25:49 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 04:25:51 INFO - PID 1652 | RSS: Main: 270667776 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | __start_tp_report 04:25:51 INFO - PID 1652 | _x_x_mozilla_page_load 04:25:51 INFO - PID 1652 | _x_x_mozilla_page_load_details 04:25:51 INFO - PID 1652 | |i|pagename|runs| 04:25:51 INFO - PID 1652 | |0;ai-astar;104;122;115;149;175;170;116;117;115;115 04:25:51 INFO - PID 1652 | |1;audio-beat-detection;127;160;145;137;140;150;146;115;142;130 04:25:51 INFO - PID 1652 | |2;audio-dft;191;180;270;199;253;273;286;202;283;265 04:25:51 INFO - PID 1652 | |3;audio-fft;83;55;57;84;60;62;59;62;88;56 04:25:51 INFO - PID 1652 | |4;audio-oscillator;87;95;70;73;87;104;108;109;88;93 04:25:51 INFO - PID 1652 | |5;imaging-gaussian-blur;90;108;104;90;91;118;90;91;86;90 04:25:51 INFO - PID 1652 | |6;imaging-darkroom;99;97;97;97;98;96;97;105;105;152 04:25:51 INFO - PID 1652 | |7;imaging-desaturate;92;94;90;90;93;89;87;93;92;92 04:25:51 INFO - PID 1652 | |8;json-parse-financial;50;49;49;53;50;51;50;54;44;43 04:25:51 INFO - PID 1652 | |9;json-stringify-tinderbox;59;64;57;58;70;60;58;57;70;85 04:25:51 INFO - PID 1652 | |10;stanford-crypto-aes;83;84;81;83;81;82;83;84;85;85 04:25:51 INFO - PID 1652 | |11;stanford-crypto-ccm;136;131;134;140;155;156;144;132;203;154 04:25:51 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;187;191;183;181;185;185;184;184;184;186 04:25:51 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;69;73;60;96;93;85;100;97;101;78 04:25:51 INFO - PID 1652 | __end_tp_report 04:25:51 INFO - PID 1652 | __start_cc_report 04:25:51 INFO - PID 1652 | _x_x_mozilla_cycle_collect,924 04:25:51 INFO - PID 1652 | __end_cc_report 04:25:51 INFO - PID 1652 | __startTimestamp1495020351853__endTimestamp 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | ------- Summary: start ------- 04:25:51 INFO - PID 1652 | Number of tests: 14 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:129.80 Median:119.50 stddev:25.31 (21.2%) stddev-sans-first:25.06 04:25:51 INFO - PID 1652 | Values: 104.0 122.0 115.0 149.0 175.0 170.0 116.0 117.0 115.0 115.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:143.50 stddev:12.74 (8.9%) stddev-sans-first:12.73 04:25:51 INFO - PID 1652 | Values: 127.0 160.0 145.0 137.0 140.0 150.0 146.0 115.0 142.0 130.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:240.20 Median:267.50 stddev:42.00 (15.7%) stddev-sans-first:40.60 04:25:51 INFO - PID 1652 | Values: 191.0 180.0 270.0 199.0 253.0 273.0 286.0 202.0 283.0 265.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:66.60 Median:62.00 stddev:12.96 (20.9%) stddev-sans-first:12.32 04:25:51 INFO - PID 1652 | Values: 83.0 55.0 57.0 84.0 60.0 62.0 59.0 62.0 88.0 56.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:87.00 stddev:13.36 (15.4%) stddev-sans-first:14.08 04:25:51 INFO - PID 1652 | Values: 87.0 95.0 70.0 73.0 87.0 104.0 108.0 109.0 88.0 93.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:90.00 stddev:10.46 (11.6%) stddev-sans-first:10.89 04:25:51 INFO - PID 1652 | Values: 90.0 108.0 104.0 90.0 91.0 118.0 90.0 91.0 86.0 90.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:104.30 Median:97.00 stddev:17.08 (17.6%) stddev-sans-first:18.01 04:25:51 INFO - PID 1652 | Values: 99.0 97.0 97.0 97.0 98.0 96.0 97.0 105.0 105.0 152.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:91.20 Median:92.00 stddev:2.15 (2.3%) stddev-sans-first:2.26 04:25:51 INFO - PID 1652 | Values: 92.0 94.0 90.0 90.0 93.0 89.0 87.0 93.0 92.0 92.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:50.00 stddev:3.47 (6.9%) stddev-sans-first:3.67 04:25:51 INFO - PID 1652 | Values: 50.0 49.0 49.0 53.0 50.0 51.0 50.0 54.0 44.0 43.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:62.00 stddev:8.97 (14.5%) stddev-sans-first:9.34 04:25:51 INFO - PID 1652 | Values: 59.0 64.0 57.0 58.0 70.0 60.0 58.0 57.0 70.0 85.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.50 stddev:1.45 (1.7%) stddev-sans-first:1.54 04:25:51 INFO - PID 1652 | Values: 83.0 84.0 81.0 83.0 81.0 82.0 83.0 84.0 85.0 85.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:149.00 stddev:21.44 (14.4%) stddev-sans-first:22.26 04:25:51 INFO - PID 1652 | Values: 136.0 131.0 134.0 140.0 155.0 156.0 144.0 132.0 203.0 154.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.00 Median:185.00 stddev:2.67 (1.4%) stddev-sans-first:2.73 04:25:51 INFO - PID 1652 | Values: 187.0 191.0 183.0 181.0 185.0 185.0 184.0 184.0 184.0 186.0 04:25:51 INFO - PID 1652 | 04:25:51 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.20 Median:81.50 stddev:14.47 (17.8%) stddev-sans-first:14.11 04:25:51 INFO - PID 1652 | Values: 69.0 73.0 60.0 96.0 93.0 85.0 100.0 97.0 101.0 78.0 04:25:51 INFO - PID 1652 | -------- Summary: end -------- 04:25:51 INFO - PID 1652 | 04:25:52 INFO - TEST-INFO | 1652: exit 0 04:25:52 INFO - TEST-OK | kraken | took 60379ms 04:25:52 INFO - SUITE-END | took 1172s 04:25:52 INFO - Completed test suite (00:19:32) 04:25:52 INFO - javascript benchmark 04:25:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7836.407824145078, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2919.6496420429553, 3360.462326027836, 3385.949210761839, 3391.4743214801715, 3519.366514027475, 6066.514678825694, 6135.877282454354, 6242.906356404652, 6251.4998800096, 6258.718357673915, 4865.245886887529, 6026.126211699301, 6128.479079278256, 6129.32822722196, 6137.631742095477, 5371.785128594861, 5462.918056229159, 5473.945260547403, 5522.061249587579, 5540.113581826909, 5507.201455788908, 5519.144532597454, 5519.613627046108, 5525.11598144297, 5526.751296191681, 5270.209468579706, 5459.617826752129, 5509.614326997112, 5548.140038294071, 5582.553395728332, 5301.973490132525, 5425.0, 5465.890682186374, 5503.11950087988, 5509.173623956399, 4411.580899814512, 5270.525652691276, 5318.680879147263, 5338.092524270884, 5343.893122137535, 4933.679310844784, 6133.487685122345, 6221.129041934133, 6250.499960003189, 6256.311805701369, 6546.279909209983, 6567.542738456607, 6572.671366431659, 6598.505112116611, 6634.303398143174, 6455.193100862392, 6584.176977877765, 6631.204255489372, 6676.065350850885, 6682.0, 6325.367463253638, 6347.650879201688, 6354.332795056545, 6366.522510811661, 6393.808185754435, 6834.316568343225, 6857.691403886737, 6869.553479023947, 6870.175578930461, 6887.862242755117, 6305.211848518935, 6327.462165715966, 6398.9040164397575, 6488.026795980641, 6502.837429064311, 6576.736930522821, 6649.60102393858, 6650.53446258767, 6667.599944003376, 6698.799036028927, 6573.506986975996, 6596.505262105361, 6606.339366063356, 6609.339066093352, 6627.403533681992, 5180.430152683201, 6333.113364129025, 6354.936450635527, 6493.805185844432, 6630.270670226271, 5035.16919708244, 5297.496737809902, 5303.522682958475, 5304.814331498379, 5349.117395629678, 4690.319903613957, 4726.196546587088, 4738.691985020962, 4747.976260118678, 4767.594754445911, 6142.539309551801, 6200.410960958701, 6252.749890004349, 6315.621062736251, 6339.587926784744, 6716.1604799400075, 6729.091572637729, 6751.898721519181, 6752.223494298219, 6769.086173366631, 6265.592736472115, 6325.05124231369, 6327.367263273636, 6330.936690633127, 6331.3352098029045, 5683.857903552444, 5826.388229235869, 5834.824955251349, 5853.0635098384055, 5863.325717542537, 6031.396860313934, 6698.22970358415, 6764.898526522106, 6904.723811047502, 6904.896426553505, 6642.269350371552, 6732.697028633625, 6750.3924646782025, 6762.425193858577, 6781.6270105144695, 6834.111565496453, 6863.691133899087, 6886.724531018703, 6901.309869013058, 6905.965470172617, 6354.364563543609, 6404.103425520338, 6419.454346380423, 6448.161738973903, 6460.063290822711, 6718.025886246369, 6732.6297053660555, 6741.056252124608, 6758.695858686273, 6770.390664840188, 5207.869803254797, 5349.652272602269, 6407.231132264158, 6522.315156908457, 6538.901916471352, 6298.653574053471, 6336.398042185985, 6530.053142294436, 6553.279139294578, 6557.475401967927, 5992.0, 6368.108464815023, 6383.712732926937, 6393.552451328363, 6461.450776684034, 6320.209973753281, 6573.901391479036, 6576.80269591913, 6583.80248592543, 6595.505337099833, 6180.536459765445, 6282.528810339334, 6394.648294343856, 6395.744170233324, 6429.871402571829], "value": 6032.616077286268, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.9930000699993, 1100.9834852477209, 1101.0750969185883, 1112.7162573543744, 1241.0815996162842, 37623.81188094059, 38117.99999999996, 38165.85502434924, 38186.8090659547, 38215.0, 38056.61943380565, 38101.80949095251, 38373.23253534934, 38386.8080659597, 38519.807400962956, 7498.0, 7699.076110866704, 7892.368610511146, 7905.486143400689, 8427.789305267355, 7396.260373962587, 7780.338671212954, 8422.663093476254, 8427.536485493296, 8434.40959132861, 7069.399101076415, 8375.120612335708, 8375.246227839492, 8458.0, 8463.195996380335, 8323.875141872877, 8392.118827523114, 8413.0, 8418.98972123343, 8452.28155606774, 8208.958955205186, 8283.792905177359, 8292.087870334259, 8307.792305192359, 8321.16788321169, 36636.26727465436, 36870.44694329587, 36890.631093688986, 36954.0, 37047.0, 3589.3180295743987, 3610.097475631092, 3627.4740162676526, 3629.2560025195035, 3629.6370362963758, 30085.548716769266, 30119.548206776915, 30159.396812063635, 30246.09261722152, 30297.394052118834, 2774.751361887159, 2995.9400811983637, 3104.6895310469, 3108.5026395776567, 3109.8911538096286, 232393.9999999983, 249391.0, 300124.0, 302256.0, 309764.0, 42738.78630606827, 43496.13007739827, 45892.0, 46034.539654603024, 47176.52823471721, 48492.3027694029, 48583.75708121437, 48592.0, 48600.27099593509, 48657.0, 13845.723085538233, 13963.511277105252, 13965.371558279901, 13976.790348144787, 14017.088889222168, 11037.006669399792, 11099.556017759362, 11178.832317515244, 11182.832257516144, 11202.767695553483, 13122.737545249232, 13236.801447978096, 13265.867341326462, 13269.800952985714, 13373.331333433289, 5666.490015898589, 5713.74288157034, 5725.828225153252, 5726.1410788381245, 5743.770249190069, 1023.4217667018111, 1035.6168217759478, 1040.968770936858, 1065.7708592652616, 1075.9677209683723, 22262.554748905255, 23314.184003560134, 24545.545047287207, 24571.63142552863, 24712.629310560354, 1112.57165991094, 1115.7322242661864, 1126.1666366888608, 1152.8904754048351, 1154.8440960470398, 10420.843687344695, 12418.75162496745, 12537.185082969578, 12576.559820406243, 12627.368631568384, 316329.0, 317105.0, 319251.0, 319441.0, 319868.0, 42127.94680133021, 51120.21079262208, 51253.97492050213, 51309.48690513047, 51596.22605660918, 1428.250168661461, 1530.8469153084602, 1543.0664448008793, 1549.6590750034975, 1564.6323114068412, 22135.66796498086, 23801.404964876016, 23835.52328953446, 23876.40308992289, 23959.0, 36891.44662830006, 36998.81500592588, 37002.259954801295, 37066.444003339435, 37158.628413715516, 229253.0, 232529.0, 232824.0, 232832.0, 233072.83463582574, 33812.661873381934, 34125.82937085299, 34345.82827085849, 35558.0, 35720.464193037646, 42106.578934210265, 44405.11189776122, 44408.11183776371, 44446.0, 44477.777611111735, 29324.56013159847, 29952.400951981275, 30370.240743981576, 31007.84496077505, 31032.689673103883, 30268.84865575746, 30612.846935765177, 31004.68995310018, 31166.844165779024, 31270.218244544067, 35414.0, 35882.0, 36850.26299474049, 37027.07432314214, 37124.257514849014, 30280.0, 31079.533806993364, 31301.904433344724, 31305.686943130277, 31344.0, 23335.766642333358, 24432.877835610707, 24593.87703061473, 24695.62956555689, 24702.25893223206], "value": 17227.358843358754, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.9077848262623, 435.32833917755823, 435.36219438522556, 446.3438745044784, 448.75542829158104, 21699.674504882438, 21766.0, 22018.559628807434, 22486.10055597774, 22577.322680319576, 21151.47121321964, 21878.671819922714, 21883.6717449238, 22111.778882211212, 22371.44071398212, 5892.558058145635, 6082.847928801771, 6131.172291740602, 6140.293866205398, 6146.692665366736, 4989.176785830334, 5820.796272130477, 5847.210626565423, 6067.484263837579, 6161.753529858823, 5164.586833053366, 5918.88162236755, 5996.040633498641, 6065.878682426349, 6071.78748743794, 5971.552133589998, 6012.158297838328, 6096.603720758181, 6107.0, 6107.083937409379, 4708.246680531099, 5293.100172970604, 5347.6256662033675, 5386.48828361305, 5399.136138217906, 26537.601935970975, 28003.85998070017, 28059.158225253275, 28248.435031299257, 28416.715832841615, 2156.9029393677324, 2222.855514391559, 2226.243077353691, 2228.119892642397, 2228.754836967932, 17821.554461138447, 17956.461306160836, 18099.457016289532, 18299.08504574766, 18556.628867422576, 2116.121809449077, 2125.4048866317457, 2168.316980151263, 2169.143188440573, 2169.3383518026994, 138401.0, 164556.0, 164998.0, 167159.0, 168842.0, 12398.0, 12442.75114497705, 12562.120651554485, 12579.433925473375, 12822.294773787346, 16249.837501625068, 16499.6700065998, 16507.33970641185, 16521.6695666086, 16531.586710332096, 8250.0, 8629.352798540134, 8813.867791983126, 8953.059928707522, 9111.453312801254, 8235.0, 8393.034800997963, 8398.580070996426, 8550.273226775793, 8563.486190828446, 6450.516211284173, 7013.859722805515, 7697.846043079219, 7965.8805117923275, 8026.398020148512, 2136.989315053446, 2154.2029449103934, 2164.0, 2164.296603603835, 2366.6450032495263, 954.160338901762, 960.2366118935463, 969.5588507229194, 992.0872797026752, 1013.3008224325192, 19126.521836954187, 19325.937073461093, 21163.365099047052, 21622.675659865115, 21815.23646672359, 1000.5897581991348, 1002.4737013068197, 1021.4637315409469, 1025.7281820317621, 1032.318669678024, 10498.475076246157, 11147.0, 11210.55157793679, 11223.775524489465, 11274.154438417152, 513666.0, 546836.0, 548098.0, 557647.2117639385, 560980.0, 124245.0, 126310.36844815717, 131390.68609313975, 131509.34245328713, 134660.6533934648, 1197.9940100299443, 1230.9630711078682, 1250.6998320403038, 1254.5295514182183, 1260.7478504299177, 22030.339089827332, 22240.110395583997, 22755.31734047981, 22786.974586143995, 23375.649365259877, 40965.97585060397, 54862.72568637131, 56524.0, 56526.71736641291, 58314.0, 78089.38302161865, 79180.0, 80187.59906200432, 80601.59699201466, 80709.59645201972, 8245.299149572353, 8562.914370856211, 8588.441751286146, 8631.91368086311, 8919.331050171133, 13910.37403316833, 14010.299485025911, 14051.227182505061, 14392.568222953329, 14438.278086095863, 8971.0, 10628.52171652262, 11447.969682728595, 11905.940470297593, 12030.699232519257, 10120.89879101229, 11171.72070698239, 11301.43492825339, 11313.660590182308, 11633.0, 11961.457193808017, 12030.699232519257, 12086.75826483483, 12155.02759779198, 12318.384080796104, 7898.921010790048, 8092.028956525254, 8614.91385086141, 8939.329550283624, 9010.414323068979, 11543.4805433754, 11550.82673759911, 11646.359450230319, 11693.88306116928, 11864.99147572466], "value": 10431.22563965511, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.9929350353248, 1429.8141241638587, 1484.2430360516141, 1485.9227060381218, 1495.080525476832, 10131.290809643328, 10339.22455815815, 11431.14266430018, 11839.644810655673, 11917.880821191797, 11310.755816860139, 11663.708407289821, 11671.1830171888, 11673.766524669501, 11677.299362038284, 2180.760116387196, 2495.9750402496015, 2555.961660575093, 2561.065211197909, 2562.423454722691, 2499.3126890105186, 2556.654851595039, 2558.0, 2559.6672432583737, 2563.3719738664054, 2527.8736063196857, 2537.8223524353302, 2541.2884392370165, 2542.478791847676, 2546.9235922922344, 2487.7387874273213, 2495.1765917247358, 2499.512595043967, 2503.161440917295, 2515.195137555983, 2078.688196770481, 2467.247489515697, 2485.155047283932, 2489.825712200147, 2500.4249022724875, 11415.771684566345, 11459.083273338112, 11464.31214127155, 11499.252548584398, 11585.304881707125, 6460.93539064608, 6722.4285935695525, 6726.3609957054005, 6726.730930762764, 6733.124693789825, 4070.61329173728, 4250.766207858566, 4304.117655880537, 4360.367746676746, 4398.8020539075815, 1166.9824952625718, 1168.6669299249672, 1275.8787915148046, 1279.5969269680113, 1282.5126451948292, 16308.103054332007, 16344.75482867758, 16429.342826286935, 16438.424655137133, 16459.423920162735, 5782.576329108236, 6036.5170786336985, 6122.816315510542, 6164.291106522717, 6181.567290289637, 13772.10481318711, 13782.517611883535, 13784.897208223318, 13813.792793108112, 13829.0, 6781.9827025945515, 6797.9660101700165, 6804.251532331439, 6811.693473793691, 6823.761168359183, 6071.696415179224, 6253.74985000604, 6288.496920246369, 6316.368363163647, 6319.05214217871, 4220.556841531657, 4356.215881141425, 4398.032432864765, 4405.537418571068, 4421.358902959053, 1258.1758947889148, 1260.7793636113643, 1266.949322027127, 1269.7904845700357, 1271.7265787855656, 667.6895243711697, 791.6516732637621, 818.1041759273586, 824.6371596497522, 825.1377310710322, 5158.432572417031, 5614.578906582023, 5738.7948530809, 6157.322694503601, 6340.524460665469, 586.4839390176442, 589.5195415736162, 601.8736065426222, 603.4177019176516, 605.4883623338272, 3759.812009399574, 3790.753601015925, 3883.3204189266767, 3906.882793516199, 4319.8488052918, 12771.808422873664, 13912.860871391358, 14401.855981440052, 14948.701025979637, 14955.177465239298, 14299.07056041354, 15010.099394036393, 15025.849741502663, 15026.248687565578, 15049.774253386207, 8155.143709910492, 8542.78643033929, 8561.957190214009, 8570.74287771368, 8584.613692383859, 7025.789226323219, 7042.330978557029, 7178.066851309296, 7195.6761945713615, 7246.89129663066, 12995.48018079304, 13068.150570212905, 13137.540186093442, 13296.135751176143, 13505.729885402434, 13459.192448453125, 13583.117097388638, 13714.17714937107, 13721.862781372058, 13997.44010239579, 8617.48295102296, 9137.314701397288, 9369.718908432758, 9526.857097143687, 9541.998090200428, 11067.0, 11113.833292500458, 11165.553377864795, 11176.944115279372, 11191.72020699489, 8384.916150838413, 8512.702055428277, 8580.184882436159, 8600.354973376896, 8633.093525180017, 8344.958275208584, 8376.413651044368, 8439.70461033861, 8465.111163327761, 8496.192861678132, 8065.516069035877, 8134.389920756085, 8147.633356498854, 8166.42835001568, 8210.83578328442, 6006.699665016644, 6123.203983482119, 6190.566660333733, 6196.81409557714, 6263.872502949513, 5998.8500287491415, 6168.568200226121, 6262.248530176225, 6287.874242515032, 6289.465395441439], "value": 5319.283441151579, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.80800847521147, 355.52714889197364, 402.6175133623059, 404.07466900797175, 406.6096547314577, 5326.973365133169, 6063.181470501481, 6307.653079080649, 6316.473410635748, 6353.428191462767, 5754.625949313302, 6211.254649442073, 6268.811935641938, 6298.748050077991, 6329.303776584584, 4785.258284965827, 4791.089692958344, 4791.137595232856, 4797.496262892399, 4810.422749270093, 3774.7168962327796, 4367.388565600819, 4522.09558088382, 4529.818807247706, 4812.494688057756, 4405.096955124208, 4456.22016147173, 4486.528914463983, 4494.730316181023, 4498.955010449887, 4158.708890377675, 4506.166359223538, 4510.661700372485, 4514.706544074657, 4515.14212299662, 3807.58116607173, 3833.11838277195, 3837.9232415351535, 3883.9513331400644, 3891.1439483313625, 5018.824341148043, 6015.428534289235, 6016.96991515044, 6088.573799834014, 6104.694765261719, 5404.243405923174, 5410.594205434608, 5424.457554244584, 5442.4489020628935, 5495.835124946258, 6502.739890404426, 6604.928708150733, 6634.071230027752, 6660.0, 6680.498962577826, 3204.6314673812344, 3442.1910850950007, 3513.683768460851, 4066.3900414937702, 4309.504406993205, 5653.925754106747, 6711.295313992009, 6998.860022799567, 7091.971664108727, 7154.928450715478, 4380.364847097217, 4434.933475997863, 4441.222786012435, 4445.377647129404, 4446.777661116996, 4492.326151077365, 4505.234110201273, 4512.593866552026, 4516.480604730433, 4519.525449827786, 5202.92195617066, 5302.39022512416, 5374.769470669628, 5413.539849112791, 5418.241446197536, 5570.0, 6587.002913674362, 6673.666316684146, 6692.196936367667, 6738.59568425896, 1513.7435928179584, 1543.1204213598141, 1544.3745283160338, 1546.636540413002, 1552.8447155284607, 879.7272845417813, 940.849464085743, 942.5475771629519, 979.0, 998.1415982255255, 5674.858128546737, 6170.500947116425, 6214.906776398358, 6227.626342419469, 6279.335975966373, 6954.269801670853, 6976.232614412411, 7003.579785212802, 7026.683799229047, 7227.566346019255, 5937.465628093492, 6132.41742034506, 6202.751889924352, 6221.751129954841, 6245.500359971192, 3059.128148477673, 3561.756093660977, 3574.37448446526, 3583.175869550018, 3604.6395360464267, 6422.710978006001, 6462.352100214415, 6865.244823069458, 7080.681369338392, 7092.716291348288, 7040.295970402919, 7044.436445084381, 7049.753258635923, 7086.893696594555, 7089.7873063808165, 5668.036433806263, 6239.126522286792, 6962.303769623099, 7182.7126914923865, 7263.201047884834, 5849.0, 6818.454523638196, 7062.1878483975, 7088.468364872552, 7099.574025558483, 5533.142362933751, 6098.146259523582, 6723.932760672331, 6873.828154296182, 6966.024756534191, 5629.746661400329, 6623.536352455475, 7257.2742725727, 7264.200937896721, 7291.192890736998, 5048.116579598629, 5097.776533631827, 5098.0, 5138.640295179302, 5151.7166555839785, 6411.8397040072505, 6819.420349270367, 6962.651867406508, 7018.5788852669, 7074.327938846007, 5420.4850539198715, 5812.622179558315, 6173.783917562864, 6735.562188457735, 6788.9660551696925, 6668.6665666717445, 6739.865202695821, 6752.729890804313, 6807.897881531682, 6821.045053692388, 5678.6876721779045, 6654.234763002123, 6678.0, 6682.231543372574, 6696.430803381572, 5702.885942281214, 6580.144581204413, 6753.2233793112455, 6767.0, 6782.914733642593, 5984.551158663031, 6020.518358531394, 6156.6921653918025, 6639.966800166161, 6711.563748356341], "value": 4828.804479205926, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.4106358635643, 820.7619790260825, 848.0374774630792, 851.4337965253108, 855.8545047341951, 16432.096234707085, 16915.238814253356, 16949.745753813688, 18443.815561844378, 19097.809021909776, 17670.82329176708, 18208.908955455237, 18333.26666933321, 18351.35770248038, 18695.317421432068, 986.3588667366233, 1003.2074661017787, 1004.9598016079348, 1006.3257617396362, 1006.7432804634808, 983.8130755156533, 1005.6480231918806, 1011.3780025284434, 1011.6560369474378, 1014.614446510325, 1044.858944042556, 1057.9629712960048, 1063.2769716592713, 1064.5741703318656, 1065.137238836541, 1022.6676330192718, 1031.494567661844, 1051.9000694933968, 1055.3931489393592, 1056.4400867540216, 759.2483441393036, 771.0785611194568, 772.474717192309, 775.3796962430033, 779.1857508903153, 16757.581060473585, 16873.40943066999, 16877.324907003705, 16887.240074196692, 16889.324427022904, 16851.241694123793, 16940.23768930401, 17008.91495542527, 17248.655026899392, 17307.740383894252, 13694.452221911111, 13698.38357273925, 13914.443422263199, 13991.950603704763, 14028.298585070706, 3523.841427135785, 3573.160307327776, 3574.338747331726, 3575.6781889630056, 3579.8210089495683, 108802.0, 111748.88251117547, 117813.0, 124465.37767311106, 125421.11868321983, 13505.52730654442, 13793.310334483236, 13799.103058301178, 13812.930935345259, 13880.375383107785, 24959.37601559939, 25138.497230055298, 25417.110401135873, 25845.741542584332, 25957.870210648827, 6911.412529934912, 7029.3673569379, 7048.894266586005, 7049.506534542533, 7053.0, 3172.6192856856865, 3212.726918211931, 3214.2125179331215, 3217.6299725531862, 3220.581324427856, 4268.125034367947, 4294.076773493652, 4378.255696531597, 4406.294992801137, 4408.272635015187, 916.7295647783919, 934.0939288889766, 946.3091942881734, 949.6011675096476, 951.7620594851287, 624.7719582352499, 666.3436515032689, 676.7969609117246, 677.2719326723693, 691.6507163882208, 10298.536565854554, 11214.551417943354, 11387.0, 12011.339376334385, 13119.672008199872, 610.6671863834221, 657.0735263278754, 660.6993817812884, 665.1519312876121, 694.4201591671011, 5267.446918073547, 5327.600429967768, 5397.568194544427, 5610.1584762285165, 5628.943710562924, 75674.62162689152, 80671.59664201642, 101710.49144754228, 104503.4774826121, 106681.46659266653, 79875.41495020635, 80615.19384806076, 83898.58050709829, 87970.0, 88362.55818720994, 55893.7205313979, 73240.26759732334, 73633.0, 73973.0, 74088.0, 12066.939665301968, 12353.38233088324, 12357.196782209128, 12424.503019879103, 12460.00319974418, 38449.4232586517, 38476.2304753909, 38550.6144938547, 38914.80542597269, 39364.803175983936, 47405.28892066553, 59711.20866373842, 65655.0, 70143.0, 72173.0, 15292.617684557976, 16004.91997540005, 16141.59646008859, 16376.918115409348, 16587.84358033448, 17709.468715938543, 18331.35840245585, 18508.259669613064, 18578.81421185771, 18618.720719188954, 16267.593310166867, 16726.916365418096, 16965.91517042407, 17060.061696606805, 17170.0, 17600.207990640196, 17758.11209439549, 18348.715589909112, 18655.72016419782, 18737.06314684233, 14587.489437869624, 17121.74317385265, 17566.560835979202, 18224.635507290044, 18365.632687346446, 9556.948735639213, 11439.771204575696, 11502.022328102204, 11561.684235697307, 11916.0, 12042.458089386173, 12614.432350544064, 13222.669433264246, 13682.794758078438, 13835.169889806644], "value": 8316.097706971197, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1573.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 122.0, 115.0, 149.0, 175.0, 170.0, 116.0, 117.0, 115.0, 115.0], "value": 129.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 160.0, 145.0, 137.0, 140.0, 150.0, 146.0, 115.0, 142.0, 130.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 180.0, 270.0, 199.0, 253.0, 273.0, 286.0, 202.0, 283.0, 265.0], "value": 240.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 55.0, 57.0, 84.0, 60.0, 62.0, 59.0, 62.0, 88.0, 56.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 95.0, 70.0, 73.0, 87.0, 104.0, 108.0, 109.0, 88.0, 93.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 108.0, 104.0, 90.0, 91.0, 118.0, 90.0, 91.0, 86.0, 90.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 97.0, 97.0, 98.0, 96.0, 97.0, 105.0, 105.0, 152.0], "value": 104.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 90.0, 90.0, 93.0, 89.0, 87.0, 93.0, 92.0, 92.0], "value": 91.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 53.0, 50.0, 51.0, 50.0, 54.0, 44.0, 43.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 57.0, 58.0, 70.0, 60.0, 58.0, 57.0, 70.0, 85.0], "value": 63.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 81.0, 83.0, 81.0, 82.0, 83.0, 84.0, 85.0, 85.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 131.0, 134.0, 140.0, 155.0, 156.0, 144.0, 132.0, 203.0, 154.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 191.0, 183.0, 181.0, 185.0, 185.0, 184.0, 184.0, 184.0, 186.0], "value": 185.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 60.0, 96.0, 93.0, 85.0, 100.0, 97.0, 101.0, 78.0], "value": 85.2, "unit": "score"}]}]} 04:25:53 INFO - Return code: 0 04:25:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:25:53 INFO - # TBPL SUCCESS # 04:25:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:25:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:25:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:25:53 INFO - 2017-05-17 04:25:53.141 screenresolution[1659:10626] starting screenresolution argv=screenresolution get 04:25:53 INFO - 2017-05-17 04:25:53.144 screenresolution[1659:10626] Display 0: 1600x1200x32@60 04:25:53 INFO - 2017-05-17 04:25:53.150 screenresolution[1660:10629] starting screenresolution argv=screenresolution list 04:25:53 INFO - Available Modes on Display 0 04:25:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:25:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:25:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:25:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:25:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:25:53 INFO - Intel Iris: 04:25:53 INFO - Chipset Model: Intel Iris 04:25:53 INFO - Type: GPU 04:25:53 INFO - Bus: Built-In 04:25:53 INFO - VRAM (Dynamic, Max): 1536 MB 04:25:53 INFO - Vendor: Intel (0x8086) 04:25:53 INFO - Device ID: 0x0a2e 04:25:53 INFO - Revision ID: 0x0009 04:25:53 INFO - Displays: 04:25:53 INFO - Display: 04:25:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:25:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:25:53 INFO - Display Serial Number: 1600x1200 60 04:25:53 INFO - Main Display: Yes 04:25:53 INFO - Mirror: Off 04:25:53 INFO - Online: Yes 04:25:53 INFO - Rotation: Supported 04:25:53 INFO - Return code: 0 04:25:53 INFO - Running post-action listener: _resource_record_post_action 04:25:53 INFO - [mozharness: 2017-05-17 11:25:53.384432Z] Finished run-tests step (success) 04:25:53 INFO - Running post-run listener: _resource_record_post_run 04:25:53 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 45980672; Write bytes: 531940352; Read time: 485; Write time: 2368 04:25:53 INFO - TinderboxPrint: CPU usage
54.2% 04:25:53 INFO - TinderboxPrint: I/O read bytes / time
45,980,672 / 485 04:25:53 INFO - TinderboxPrint: I/O write bytes / time
531,940,352 / 2,368 04:25:53 INFO - TinderboxPrint: CPU idle
2,189.4 (45.8%) 04:25:53 INFO - TinderboxPrint: CPU system
961.2 (20.1%) 04:25:53 INFO - TinderboxPrint: CPU user
1,626.9 (34.1%) 04:25:53 INFO - TinderboxPrint: Swap in / out
255,504,384 / 0 04:25:53 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201699840; Write bytes: 197699072; Read time: 15881; Write time: 990 04:25:53 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 44952576; Write bytes: 313503232; Read time: 469; Write time: 1325 04:25:53 INFO - Running post-run listener: _upload_blobber_files 04:25:53 INFO - Blob upload gear active. 04:25:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:25:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:25:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:25:54 INFO - (blobuploader) - INFO - Open directory for files ... 04:25:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:25:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:25:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:55 INFO - (blobuploader) - INFO - Done attempting. 04:25:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:25:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:25:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:56 INFO - (blobuploader) - INFO - Done attempting. 04:25:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:25:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:25:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:57 INFO - (blobuploader) - INFO - Done attempting. 04:25:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:25:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:26:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:01 INFO - (blobuploader) - INFO - Done attempting. 04:26:01 INFO - (blobuploader) - INFO - Iteration through files over. 04:26:01 INFO - Return code: 0 04:26:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:26:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:26:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdb120ab852f9c722df62a1a6273e867451cd243c52ae04856f1a67498b47bcc35130312ac30a65b7b844345dc02a6581bf9a6e2d6873fd02b9aea9757f50184", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f9aa86f09910100c11c3612abbe029688b2fc571f7d3cb4557892e40789eeeb0ac4c6270c41f19e1cb319735720075f1c7740351d147665c7d4ac866938c04", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da906401b00deae9ab3fb7604b698282c302c6be44bc84352fb49607e9c5a39abe48d2f20be03d6833fdcd77737f8d86a1cfacd1fbb70a08c11c677d73bd5eea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7c4eca705f5cc92393eae1ce13e64a2c1fe1b5ef13bf3e5ad88062bd8f5754709c6907241947157fd5951a9d1a32b27c51d5fbdc100a7aca6d55506ff88fce"} 04:26:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:26:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:26:01 INFO - Contents: 04:26:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdb120ab852f9c722df62a1a6273e867451cd243c52ae04856f1a67498b47bcc35130312ac30a65b7b844345dc02a6581bf9a6e2d6873fd02b9aea9757f50184", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f9aa86f09910100c11c3612abbe029688b2fc571f7d3cb4557892e40789eeeb0ac4c6270c41f19e1cb319735720075f1c7740351d147665c7d4ac866938c04", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da906401b00deae9ab3fb7604b698282c302c6be44bc84352fb49607e9c5a39abe48d2f20be03d6833fdcd77737f8d86a1cfacd1fbb70a08c11c677d73bd5eea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7c4eca705f5cc92393eae1ce13e64a2c1fe1b5ef13bf3e5ad88062bd8f5754709c6907241947157fd5951a9d1a32b27c51d5fbdc100a7aca6d55506ff88fce"} 04:26:01 INFO - Running post-run listener: copy_logs_to_upload_dir 04:26:01 INFO - Copying logs to upload dir... 04:26:01 INFO - mkdir: /builds/slave/test/build/upload/logs 04:26:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.677794 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-17 04:26:01.172938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 04:26:01.176848) ========= 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.95Ip9MFO4o/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.LagkcXus3a/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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/Firefox/sha512/fdb120ab852f9c722df62a1a6273e867451cd243c52ae04856f1a67498b47bcc35130312ac30a65b7b844345dc02a6581bf9a6e2d6873fd02b9aea9757f50184", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f9aa86f09910100c11c3612abbe029688b2fc571f7d3cb4557892e40789eeeb0ac4c6270c41f19e1cb319735720075f1c7740351d147665c7d4ac866938c04", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da906401b00deae9ab3fb7604b698282c302c6be44bc84352fb49607e9c5a39abe48d2f20be03d6833fdcd77737f8d86a1cfacd1fbb70a08c11c677d73bd5eea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7c4eca705f5cc92393eae1ce13e64a2c1fe1b5ef13bf3e5ad88062bd8f5754709c6907241947157fd5951a9d1a32b27c51d5fbdc100a7aca6d55506ff88fce"} build_url:https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011067 build_url: 'https://queue.taskcluster.net/v1/task/SYfxtJtKRe2N5dp7upYKYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdb120ab852f9c722df62a1a6273e867451cd243c52ae04856f1a67498b47bcc35130312ac30a65b7b844345dc02a6581bf9a6e2d6873fd02b9aea9757f50184", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f9aa86f09910100c11c3612abbe029688b2fc571f7d3cb4557892e40789eeeb0ac4c6270c41f19e1cb319735720075f1c7740351d147665c7d4ac866938c04", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da906401b00deae9ab3fb7604b698282c302c6be44bc84352fb49607e9c5a39abe48d2f20be03d6833fdcd77737f8d86a1cfacd1fbb70a08c11c677d73bd5eea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7c4eca705f5cc92393eae1ce13e64a2c1fe1b5ef13bf3e5ad88062bd8f5754709c6907241947157fd5951a9d1a32b27c51d5fbdc100a7aca6d55506ff88fce"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 04:26:01.211202) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 04:26:01.212601) ========= 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.95Ip9MFO4o/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.LagkcXus3a/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005049 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 04:26:01.263420) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 04:26:01.263873) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 04:26:01.264316) ========= ========= Total master_lag: 0.49 =========