builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0113 starttime: 1496165466.99 results: success (0) revision: 268cfff98df1cc643df7f79dab467043fd1837f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:06.993783) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:06.994203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:06.994504) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:07.018410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:07.019224) ========= 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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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-30 10:31:07-- 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% 20.9M=0.001s 2017-05-30 10:31:07 (20.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119250 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:07.359704) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:07.360040) ========= 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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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.023179 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:07.412103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-30 10:31:07.414504) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 268cfff98df1cc643df7f79dab467043fd1837f1 --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 268cfff98df1cc643df7f79dab467043fd1837f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XHmYWnefa8/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.cOw3TcFC8Y/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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-30 10:31:07,498 truncating revision to first 12 chars 2017-05-30 10:31:07,498 Setting DEBUG logging. 2017-05-30 10:31:07,499 attempt 1/10 2017-05-30 10:31:07,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/268cfff98df1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 10:31:09,330 unpacking tar archive at: mozilla-central-268cfff98df1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.109887 ========= master_lag: 0.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-30 10:31:09.811732) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:09.812061) ========= script_repo_revision: 268cfff98df1cc643df7f79dab467043fd1837f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:09.812458) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:09.812740) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 10:31:09.831939) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-30 10:31:09.832404) ========= /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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:31:10 INFO - MultiFileLogger online at 20170530 10:31:10 in /builds/slave/test 10:31:10 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 10:31:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:31:10 INFO - {'append_to_log': False, 10:31:10 INFO - 'base_work_dir': '/builds/slave/test', 10:31:10 INFO - 'blob_upload_branch': 'Firefox', 10:31:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:31:10 INFO - 'branch': 'Firefox', 10:31:10 INFO - 'buildbot_json_path': 'buildprops.json', 10:31:10 INFO - 'config_files': ('talos/mac_config.py',), 10:31:10 INFO - 'default_actions': ('clobber', 10:31:10 INFO - 'read-buildbot-config', 10:31:10 INFO - 'download-and-extract', 10:31:10 INFO - 'populate-webroot', 10:31:10 INFO - 'create-virtualenv', 10:31:10 INFO - 'install', 10:31:10 INFO - 'setup-mitmproxy', 10:31:10 INFO - 'run-tests'), 10:31:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:31:10 INFO - 'download_minidump_stackwalk': True, 10:31:10 INFO - 'download_symbols': 'ondemand', 10:31:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:31:10 INFO - 'tooltool.py': '/tools/tooltool.py', 10:31:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:31:10 INFO - '/tools/misc-python/virtualenv.py')}, 10:31:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:31:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:31:10 INFO - 'gecko_profile': False, 10:31:10 INFO - 'gecko_profile_interval': 0, 10:31:10 INFO - 'installer_path': 'installer.exe', 10:31:10 INFO - 'log_level': 'info', 10:31:10 INFO - 'log_name': 'talos', 10:31:10 INFO - 'log_to_console': True, 10:31:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:31:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:31:10 INFO - 'opt_config_files': (), 10:31:10 INFO - 'pip_index': False, 10:31:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:31:10 INFO - 'cmd': ('bash', 10:31:10 INFO - '-c', 10:31:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:31:10 INFO - 'enabled': True, 10:31:10 INFO - 'halt_on_failure': False, 10:31:10 INFO - 'name': 'check_screen_resolution'},), 10:31:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:31:10 INFO - 'cmd': ('bash', 10:31:10 INFO - '-c', 10:31:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:31:10 INFO - 'enabled': True, 10:31:10 INFO - 'halt_on_failure': False, 10:31:10 INFO - 'name': 'check_screen_resolution'},), 10:31:10 INFO - 'run_cmd_checks_enabled': True, 10:31:10 INFO - 'suite': 'dromaeojs-e10s', 10:31:10 INFO - 'system_bits': '32', 10:31:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:31:10 INFO - 'title': 't-yosemite-r7-0113', 10:31:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:31:10 INFO - 'use_talos_json': True, 10:31:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:31:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:31:10 INFO - 'work_dir': 'build'} 10:31:10 INFO - [mozharness: 2017-05-30 17:31:10.036852Z] Running clobber step. 10:31:10 INFO - Running pre-action listener: _resource_record_pre_action 10:31:10 INFO - Running main action method: clobber 10:31:10 INFO - rmtree: /builds/slave/test/build 10:31:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:31:11 INFO - Running post-action listener: _resource_record_post_action 10:31:11 INFO - [mozharness: 2017-05-30 17:31:11.861369Z] Finished clobber step (success) 10:31:11 INFO - [mozharness: 2017-05-30 17:31:11.861532Z] Running read-buildbot-config step. 10:31:11 INFO - Running pre-action listener: _resource_record_pre_action 10:31:11 INFO - Running main action method: read_buildbot_config 10:31:11 INFO - Using buildbot properties: 10:31:11 INFO - { 10:31:11 INFO - "project": "", 10:31:11 INFO - "product": "firefox", 10:31:11 INFO - "who": "ffxbld@noreply.mozilla.org", 10:31:11 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:31:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:31:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:31:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:31:11 INFO - "stage_platform": "macosx64", 10:31:11 INFO - "basedir": "/builds/slave/test", 10:31:11 INFO - "buildnumber": 80, 10:31:11 INFO - "platform": "yosemite_r7", 10:31:11 INFO - "parent_task_id": "XUqH7yAUT5qUCZvyGoPsgA", 10:31:11 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:31:11 INFO - "slavebuilddir": "test", 10:31:11 INFO - "taskId": "VCYYsLw1RR6IDnlvKVVMpQ", 10:31:11 INFO - "branch": "mozilla-central", 10:31:11 INFO - "script_repo_revision": "production", 10:31:11 INFO - "revision": "268cfff98df1cc643df7f79dab467043fd1837f1", 10:31:11 INFO - "slavename": "t-yosemite-r7-0113", 10:31:11 INFO - "repo_path": "mozilla-central" 10:31:11 INFO - } 10:31:11 INFO - Finding installer, test and symbols from parent task. 10:31:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VCYYsLw1RR6IDnlvKVVMpQ'}, attempt #1 10:31:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VCYYsLw1RR6IDnlvKVVMpQ'}, attempt #1 10:31:12 INFO - Task dependencies: fEovFUawTrGu5KQGNbgeCQ XUqH7yAUT5qUCZvyGoPsgA 10:31:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA'}, attempt #1 10:31:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:31:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/test_packages.json 10:31:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/target.crashreporter-symbols.zip 10:31:13 INFO - Running post-action listener: _resource_record_post_action 10:31:13 INFO - [mozharness: 2017-05-30 17:31:13.224500Z] Finished read-buildbot-config step (success) 10:31:13 INFO - [mozharness: 2017-05-30 17:31:13.224638Z] Running download-and-extract step. 10:31:13 INFO - Running pre-action listener: _resource_record_pre_action 10:31:13 INFO - Running main action method: download_and_extract 10:31:13 INFO - mkdir: /builds/slave/test/build/tests 10:31:13 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')]} 10:31:13 INFO - trying https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:31:13 INFO - Downloading https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/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 10:31:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/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 10:31:14 INFO - Downloaded 1325 bytes. 10:31:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:31:14 INFO - Using the following test package requirements: 10:31:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:31:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:31:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:31:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:31:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'jsshell-mac.zip'], 10:31:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:31:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:31:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:31:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:31:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:31:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:31:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:31:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:31:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:31:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:31:14 INFO - Fetch https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:31:15 INFO - Content-Length response header: 57633114 10:31:15 INFO - Bytes received: 57633114 10:31:33 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 10:31:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:31:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:31:33 INFO - Fetch https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:31:35 INFO - Content-Length response header: 57633114 10:31:35 INFO - Bytes received: 57633114 10:31:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:31:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:31:53 INFO - Fetch https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:31:54 INFO - Content-Length response header: 14447425 10:31:54 INFO - Bytes received: 14447425 10:31:54 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')]} 10:31:54 INFO - trying https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:31:54 INFO - Downloading https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:31:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/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 10:31:57 INFO - Downloaded 67897620 bytes. 10:31:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:31:57 INFO - mkdir: /builds/slave/test/properties 10:31:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:31:57 INFO - Writing to file /builds/slave/test/properties/build_url 10:31:57 INFO - Contents: 10:31:57 INFO - build_url:https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:31:57 INFO - Running post-action listener: _resource_record_post_action 10:31:57 INFO - Running post-action listener: set_extra_try_arguments 10:31:57 INFO - [mozharness: 2017-05-30 17:31:57.256988Z] Finished download-and-extract step (success) 10:31:57 INFO - [mozharness: 2017-05-30 17:31:57.257144Z] Running populate-webroot step. 10:31:57 INFO - Running pre-action listener: _resource_record_pre_action 10:31:57 INFO - Running main action method: populate_webroot 10:31:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:31:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:31:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:31:57 INFO - '--tppagecycles', 10:31:57 INFO - '7'], 10:31:57 INFO - 'tests': ['tsvgm']}}, 10:31:57 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:31:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:31:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:31:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:31:57 INFO - 'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:31:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:31:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:31:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:31:57 INFO - 'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['damp', 'tps']}, 10:31:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:31:57 INFO - 'tests': ['damp', 'tps']}, 10:31:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['dromaeo_dom']}, 10:31:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:31:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:31:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:31:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:31:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:31:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['a11yr', 10:31:57 INFO - 'ts_paint', 10:31:57 INFO - 'tpaint', 10:31:57 INFO - 'sessionrestore', 10:31:57 INFO - 'sessionrestore_no_auto_restore', 10:31:57 INFO - 'tabpaint']}, 10:31:57 INFO - 'other-e10s': {'tests': ['a11yr', 10:31:57 INFO - 'ts_paint', 10:31:57 INFO - 'tpaint', 10:31:57 INFO - 'sessionrestore', 10:31:57 INFO - 'sessionrestore_no_auto_restore', 10:31:57 INFO - 'tabpaint']}, 10:31:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:31:57 INFO - 'ts_paint', 10:31:57 INFO - 'tpaint', 10:31:57 INFO - 'sessionrestore', 10:31:57 INFO - 'sessionrestore_no_auto_restore', 10:31:57 INFO - 'tabpaint']}, 10:31:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:31:57 INFO - 'ts_paint', 10:31:57 INFO - 'tpaint', 10:31:57 INFO - 'sessionrestore', 10:31:57 INFO - 'sessionrestore_no_auto_restore', 10:31:57 INFO - 'tabpaint']}, 10:31:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['a11yr', 10:31:57 INFO - 'ts_paint', 10:31:57 INFO - 'tpaint', 10:31:57 INFO - 'sessionrestore', 10:31:57 INFO - 'sessionrestore_no_auto_restore', 10:31:57 INFO - 'tabpaint']}, 10:31:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['a11yr', 10:31:57 INFO - 'ts_paint', 10:31:57 INFO - 'tpaint', 10:31:57 INFO - 'sessionrestore', 10:31:57 INFO - 'sessionrestore_no_auto_restore', 10:31:57 INFO - 'tabpaint']}, 10:31:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:31:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:31:57 INFO - 'bloom_basic_ref']}, 10:31:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 10:31:57 INFO - 'talos_options': ['--mitmproxy', 10:31:57 INFO - 'mitmproxy-recording-1.mp', 10:31:57 INFO - '--firstNonBlankPaint'], 10:31:57 INFO - 'tests': ['Quantum_1']}, 10:31:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['tsvgx', 10:31:57 INFO - 'tsvgr_opacity', 10:31:57 INFO - 'tart', 10:31:57 INFO - 'tscrollx', 10:31:57 INFO - 'cart', 10:31:57 INFO - 'tsvg_static']}, 10:31:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:31:57 INFO - 'tsvgr_opacity', 10:31:57 INFO - 'tart', 10:31:57 INFO - 'tscrollx', 10:31:57 INFO - 'cart', 10:31:57 INFO - 'tsvg_static']}, 10:31:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:31:57 INFO - 'talos_options': ['--disable-e10s'], 10:31:57 INFO - 'tests': ['tp5o']}, 10:31:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:31:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:31:57 INFO - 'talos_options': ['--disable-e10s', 10:31:57 INFO - '--xperf_path', 10:31:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:31:57 INFO - 'tests': ['tp5n']}, 10:31:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:31:57 INFO - 'talos_options': ['--xperf_path', 10:31:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:31:57 INFO - 'tests': ['tp5n']}}, 10:31:57 INFO - 'talos.zip': {'path': '', 10:31:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:31:57 INFO - Running post-action listener: _resource_record_post_action 10:31:57 INFO - [mozharness: 2017-05-30 17:31:57.278304Z] Finished populate-webroot step (success) 10:31:57 INFO - [mozharness: 2017-05-30 17:31:57.278446Z] Running create-virtualenv step. 10:31:57 INFO - Running pre-action listener: _resource_record_pre_action 10:31:57 INFO - Running main action method: create_virtualenv 10:31:57 INFO - Creating virtualenv /builds/slave/test/build/venv 10:31:57 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 10:31:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:31:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:31:57 INFO - Using real prefix '/tools/python27' 10:31:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:31:58 INFO - Installing distribute.............................................................................................................................................................................................done. 10:32:01 INFO - Installing pip.................done. 10:32:01 INFO - Return code: 0 10:32:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:32:01 INFO - Reading from file tmpfile_stdout 10:32:01 INFO - Output received: 10:32:01 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) 10:32:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:32:01 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')]} 10:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:01 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')]} 10:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:01 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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:01 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 10:32:01 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 10:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:01 INFO - 'HOME': '/Users/cltbld', 10:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:01 INFO - 'LOGNAME': 'cltbld', 10:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:01 INFO - 'MOZ_NO_REMOTE': '1', 10:32:01 INFO - 'NO_EM_RESTART': '1', 10:32:01 INFO - 'PAGER': '/bin/cat', 10:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:01 INFO - 'PWD': '/builds/slave/test', 10:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:01 INFO - 'SHELL': '/bin/bash', 10:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:01 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:01 INFO - 'USER': 'cltbld', 10:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:01 INFO - 'XPC_FLAGS': '0x0', 10:32:01 INFO - 'XPC_SERVICE_NAME': '0', 10:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:02 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 10:32:02 INFO - Cleaning up... 10:32:02 INFO - Return code: 0 10:32:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:32:02 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')]} 10:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:02 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')]} 10:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:02 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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:02 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 10:32:02 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 10:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:02 INFO - 'HOME': '/Users/cltbld', 10:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:02 INFO - 'LOGNAME': 'cltbld', 10:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:02 INFO - 'MOZ_NO_REMOTE': '1', 10:32:02 INFO - 'NO_EM_RESTART': '1', 10:32:02 INFO - 'PAGER': '/bin/cat', 10:32:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:02 INFO - 'PWD': '/builds/slave/test', 10:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:02 INFO - 'SHELL': '/bin/bash', 10:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:02 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:02 INFO - 'USER': 'cltbld', 10:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:02 INFO - 'XPC_FLAGS': '0x0', 10:32:02 INFO - 'XPC_SERVICE_NAME': '0', 10:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:02 INFO - Downloading/unpacking psutil>=3.1.1 10:32:02 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 10:32:02 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 10:32:02 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 10:32:02 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 10:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:32:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:32:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:32:05 INFO - Installing collected packages: psutil 10:32:05 INFO - Running setup.py install for psutil 10:32:05 INFO - building 'psutil._psutil_osx' extension 10:32:05 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 10:32:05 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 10:32:05 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 10:32:05 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 10:32:05 INFO - building 'psutil._psutil_posix' extension 10:32:05 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 10:32:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:32:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:32:05 INFO - ^ 10:32:05 INFO - 1 warning generated. 10:32:05 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 10:32:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:32:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:32:05 INFO - Successfully installed psutil 10:32:05 INFO - Cleaning up... 10:32:05 INFO - Return code: 0 10:32:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:32:05 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')]} 10:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:05 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')]} 10:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:05 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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:05 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 10:32:05 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 10:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:05 INFO - 'HOME': '/Users/cltbld', 10:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:05 INFO - 'LOGNAME': 'cltbld', 10:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:05 INFO - 'MOZ_NO_REMOTE': '1', 10:32:05 INFO - 'NO_EM_RESTART': '1', 10:32:05 INFO - 'PAGER': '/bin/cat', 10:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:05 INFO - 'PWD': '/builds/slave/test', 10:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:05 INFO - 'SHELL': '/bin/bash', 10:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:05 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:05 INFO - 'USER': 'cltbld', 10:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:05 INFO - 'XPC_FLAGS': '0x0', 10:32:05 INFO - 'XPC_SERVICE_NAME': '0', 10:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:32:06 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 10:32:06 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 10:32:06 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 10:32:06 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 10:32:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:32:07 INFO - Installing collected packages: mozsystemmonitor 10:32:07 INFO - Running setup.py install for mozsystemmonitor 10:32:07 INFO - Successfully installed mozsystemmonitor 10:32:07 INFO - Cleaning up... 10:32:07 INFO - Return code: 0 10:32:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:07 INFO - 'HOME': '/Users/cltbld', 10:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:07 INFO - 'LOGNAME': 'cltbld', 10:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:07 INFO - 'MOZ_NO_REMOTE': '1', 10:32:07 INFO - 'NO_EM_RESTART': '1', 10:32:07 INFO - 'PAGER': '/bin/cat', 10:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:07 INFO - 'PWD': '/builds/slave/test', 10:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:07 INFO - 'SHELL': '/bin/bash', 10:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:07 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:07 INFO - 'USER': 'cltbld', 10:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:07 INFO - 'XPC_FLAGS': '0x0', 10:32:07 INFO - 'XPC_SERVICE_NAME': '0', 10:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:08 INFO - Downloading/unpacking jsonschema==2.5.1 10:32:08 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 10:32:08 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 10:32:08 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 10:32:08 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 10:32:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:32:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:09 INFO - Downloading functools32-3.2.3-2.tar.gz 10:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:32:09 INFO - warning: no files found matching '*.txt' 10:32:09 INFO - no previously-included directories found matching 'build' 10:32:09 INFO - no previously-included directories found matching 'dist' 10:32:09 INFO - no previously-included directories found matching '.git*' 10:32:09 INFO - Installing collected packages: jsonschema, functools32 10:32:09 INFO - Running setup.py install for functools32 10:32:10 INFO - warning: no files found matching '*.txt' 10:32:10 INFO - no previously-included directories found matching 'build' 10:32:10 INFO - no previously-included directories found matching 'dist' 10:32:10 INFO - no previously-included directories found matching '.git*' 10:32:10 INFO - Successfully installed jsonschema functools32 10:32:10 INFO - Cleaning up... 10:32:10 INFO - Return code: 0 10:32:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:10 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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:10 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 10:32:10 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 10:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:10 INFO - 'HOME': '/Users/cltbld', 10:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:10 INFO - 'LOGNAME': 'cltbld', 10:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:10 INFO - 'MOZ_NO_REMOTE': '1', 10:32:10 INFO - 'NO_EM_RESTART': '1', 10:32:10 INFO - 'PAGER': '/bin/cat', 10:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:10 INFO - 'PWD': '/builds/slave/test', 10:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:10 INFO - 'SHELL': '/bin/bash', 10:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:10 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:10 INFO - 'USER': 'cltbld', 10:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:10 INFO - 'XPC_FLAGS': '0x0', 10:32:10 INFO - 'XPC_SERVICE_NAME': '0', 10:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:32:10 INFO - Cleaning up... 10:32:10 INFO - Return code: 0 10:32:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:10 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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:10 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 10:32:10 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 10:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:10 INFO - 'HOME': '/Users/cltbld', 10:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:10 INFO - 'LOGNAME': 'cltbld', 10:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:10 INFO - 'MOZ_NO_REMOTE': '1', 10:32:10 INFO - 'NO_EM_RESTART': '1', 10:32:10 INFO - 'PAGER': '/bin/cat', 10:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:10 INFO - 'PWD': '/builds/slave/test', 10:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:10 INFO - 'SHELL': '/bin/bash', 10:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:10 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:10 INFO - 'USER': 'cltbld', 10:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:10 INFO - 'XPC_FLAGS': '0x0', 10:32:10 INFO - 'XPC_SERVICE_NAME': '0', 10:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:10 INFO - Downloading/unpacking blobuploader==1.2.4 10:32:10 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 10:32:10 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 10:32:10 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 10:32:10 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 10:32:12 INFO - Downloading blobuploader-1.2.4.tar.gz 10:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:32:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:32:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:12 INFO - Downloading docopt-0.6.1.tar.gz 10:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:32:12 INFO - Installing collected packages: blobuploader, requests, docopt 10:32:12 INFO - Running setup.py install for blobuploader 10:32:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:32:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:32:13 INFO - Running setup.py install for requests 10:32:13 INFO - Running setup.py install for docopt 10:32:13 INFO - Successfully installed blobuploader requests docopt 10:32:13 INFO - Cleaning up... 10:32:13 INFO - Return code: 0 10:32:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:32:13 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')]} 10:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:13 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')]} 10:32:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:13 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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:13 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 10:32:13 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 10:32:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:13 INFO - 'HOME': '/Users/cltbld', 10:32:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:13 INFO - 'LOGNAME': 'cltbld', 10:32:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:13 INFO - 'MOZ_NO_REMOTE': '1', 10:32:13 INFO - 'NO_EM_RESTART': '1', 10:32:13 INFO - 'PAGER': '/bin/cat', 10:32:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:13 INFO - 'PWD': '/builds/slave/test', 10:32:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:13 INFO - 'SHELL': '/bin/bash', 10:32:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:13 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:13 INFO - 'USER': 'cltbld', 10:32:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:13 INFO - 'XPC_FLAGS': '0x0', 10:32:13 INFO - 'XPC_SERVICE_NAME': '0', 10:32:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-Wt0sIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-I44Cmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-isYx19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-euaun5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-SB0gqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-9luJFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-SMOben-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-ZubVMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:32:14 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-la163p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:32:15 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-YqChf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:32:15 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-jl5qAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:32:15 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-dM8AhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:32:15 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-cR87S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:32:15 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-vdlv3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:15 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-UUIQMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:32:15 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-D47eLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:32:15 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-_D8D1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:32:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:32:15 INFO - Running setup.py install for manifestparser 10:32:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:32:16 INFO - Running setup.py install for mozcrash 10:32:16 INFO - Running setup.py install for mozdebug 10:32:16 INFO - Running setup.py install for mozdevice 10:32:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:32:16 INFO - Running setup.py install for mozfile 10:32:16 INFO - Running setup.py install for mozhttpd 10:32:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:32:16 INFO - Running setup.py install for mozinfo 10:32:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:32:16 INFO - Running setup.py install for mozInstall 10:32:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Running setup.py install for mozleak 10:32:17 INFO - Running setup.py install for mozlog 10:32:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Running setup.py install for moznetwork 10:32:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Running setup.py install for mozprocess 10:32:17 INFO - Running setup.py install for mozprofile 10:32:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:32:17 INFO - Running setup.py install for mozrunner 10:32:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:32:18 INFO - Running setup.py install for mozscreenshot 10:32:18 INFO - Running setup.py install for moztest 10:32:18 INFO - Running setup.py install for mozversion 10:32:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:32:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:32:18 INFO - Cleaning up... 10:32:18 INFO - Return code: 0 10:32:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:18 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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:18 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 10:32:18 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 10:32:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:18 INFO - 'HOME': '/Users/cltbld', 10:32:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:18 INFO - 'LOGNAME': 'cltbld', 10:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:18 INFO - 'MOZ_NO_REMOTE': '1', 10:32:18 INFO - 'NO_EM_RESTART': '1', 10:32:18 INFO - 'PAGER': '/bin/cat', 10:32:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:18 INFO - 'PWD': '/builds/slave/test', 10:32:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:18 INFO - 'SHELL': '/bin/bash', 10:32:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:18 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:18 INFO - 'USER': 'cltbld', 10:32:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:18 INFO - 'XPC_FLAGS': '0x0', 10:32:18 INFO - 'XPC_SERVICE_NAME': '0', 10:32:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:32:18 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-e87HlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:32:18 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)) 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:32:18 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-nNqrLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:32:18 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)) 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:32:18 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-SyCFMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:32:19 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-nm18Lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:32:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-q76lIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:32:19 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-Q_QMKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:32:19 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-JHFONN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:32:19 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-W9My6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:32:19 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-TO8OC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:32:19 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-gsmok5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:32:19 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-2pfqq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:32:19 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)) 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:32:19 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-4RDyTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:32:20 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)) 10:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:32:20 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-G0pUY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:32:20 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)) 10:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:32:20 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-mt6qFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:32:20 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)) 10:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:20 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-RCS5gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:20 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)) 10:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:32:20 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-DVfiV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:32:20 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)) 10:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:32:20 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-2KkBzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:32:20 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)) 10:32:20 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)) 10:32:20 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)) 10:32:20 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)) 10:32:20 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:32:20 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:32:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:32:20 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 10:32:20 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 10:32:20 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 10:32:20 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 10:32:21 INFO - Downloading blessings-1.6.tar.gz 10:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:32:22 INFO - Installing collected packages: blessings 10:32:22 INFO - Running setup.py install for blessings 10:32:22 INFO - Successfully installed blessings 10:32:22 INFO - Cleaning up... 10:32:22 INFO - Return code: 0 10:32:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:32:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:32:22 INFO - Reading from file tmpfile_stdout 10:32:22 INFO - Current package versions: 10:32:22 INFO - blessings == 1.6 10:32:22 INFO - blobuploader == 1.2.4 10:32:22 INFO - docopt == 0.6.1 10:32:22 INFO - functools32 == 3.2.3-2 10:32:22 INFO - jsonschema == 2.5.1 10:32:22 INFO - manifestparser == 1.1 10:32:22 INFO - mozInstall == 1.12 10:32:22 INFO - mozcrash == 1.0 10:32:22 INFO - mozdebug == 0.1 10:32:22 INFO - mozdevice == 0.51 10:32:22 INFO - mozfile == 1.2 10:32:22 INFO - mozhttpd == 0.7 10:32:22 INFO - mozinfo == 0.9 10:32:22 INFO - mozleak == 0.1 10:32:22 INFO - mozlog == 3.4 10:32:22 INFO - moznetwork == 0.27 10:32:22 INFO - mozprocess == 0.25 10:32:22 INFO - mozprofile == 0.28 10:32:22 INFO - mozrunner == 6.13 10:32:22 INFO - mozscreenshot == 0.1 10:32:22 INFO - mozsystemmonitor == 0.3 10:32:22 INFO - moztest == 0.8 10:32:22 INFO - mozversion == 1.4 10:32:22 INFO - psutil == 3.1.1 10:32:22 INFO - requests == 1.2.3 10:32:22 INFO - wsgiref == 0.1.2 10:32:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:32:22 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')]} 10:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:22 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')]} 10:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:22 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 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:22 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 10:32:22 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 10:32:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:22 INFO - 'HOME': '/Users/cltbld', 10:32:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:22 INFO - 'LOGNAME': 'cltbld', 10:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:22 INFO - 'MOZ_NO_REMOTE': '1', 10:32:22 INFO - 'NO_EM_RESTART': '1', 10:32:22 INFO - 'PAGER': '/bin/cat', 10:32:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:22 INFO - 'PWD': '/builds/slave/test', 10:32:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:22 INFO - 'SHELL': '/bin/bash', 10:32:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:22 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:22 INFO - 'USER': 'cltbld', 10:32:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:22 INFO - 'XPC_FLAGS': '0x0', 10:32:22 INFO - 'XPC_SERVICE_NAME': '0', 10:32:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:22 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)) 10:32:22 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)) 10:32:22 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)) 10:32:22 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)) 10:32:22 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)) 10:32:22 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)) 10:32:22 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)) 10:32:22 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)) 10:32:22 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)) 10:32:22 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:24 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:32:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:32:24 INFO - Installing collected packages: marionette-driver 10:32:24 INFO - Running setup.py install for marionette-driver 10:32:24 INFO - Successfully installed marionette-driver 10:32:24 INFO - Cleaning up... 10:32:24 INFO - Return code: 0 10:32:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022cbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8fb59ccc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, '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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 10:32:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:24 INFO - 'HOME': '/Users/cltbld', 10:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:24 INFO - 'LOGNAME': 'cltbld', 10:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:24 INFO - 'MOZ_NO_REMOTE': '1', 10:32:24 INFO - 'NO_EM_RESTART': '1', 10:32:24 INFO - 'PAGER': '/bin/cat', 10:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:24 INFO - 'PWD': '/builds/slave/test', 10:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:24 INFO - 'SHELL': '/bin/bash', 10:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:24 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:24 INFO - 'USER': 'cltbld', 10:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:24 INFO - 'XPC_FLAGS': '0x0', 10:32:24 INFO - 'XPC_SERVICE_NAME': '0', 10:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:32:24 INFO - Cleaning up... 10:32:24 INFO - Return code: 0 10:32:24 INFO - Running post-action listener: _resource_record_post_action 10:32:24 INFO - Running post-action listener: _start_resource_monitoring 10:32:24 INFO - Starting resource monitoring. 10:32:24 INFO - [mozharness: 2017-05-30 17:32:24.844330Z] Finished create-virtualenv step (success) 10:32:24 INFO - [mozharness: 2017-05-30 17:32:24.845321Z] Running install step. 10:32:24 INFO - Running pre-action listener: _resource_record_pre_action 10:32:24 INFO - Running main action method: install 10:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:32:25 INFO - Reading from file tmpfile_stdout 10:32:25 INFO - Detecting whether we're running mozinstall >=1.0... 10:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:32:25 INFO - Reading from file tmpfile_stdout 10:32:25 INFO - Output received: 10:32:25 INFO - Usage: mozinstall [options] installer 10:32:25 INFO - Options: 10:32:25 INFO - -h, --help show this help message and exit 10:32:25 INFO - -d DEST, --destination=DEST 10:32:25 INFO - Directory to install application into. [default: 10:32:25 INFO - "/builds/slave/test"] 10:32:25 INFO - --app=APP Application being installed. [default: firefox] 10:32:25 INFO - mkdir: /builds/slave/test/build/application 10:32:25 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'] 10:32:25 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 10:32:46 INFO - Reading from file tmpfile_stdout 10:32:46 INFO - Output received: 10:32:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:32:46 INFO - Running post-action listener: _resource_record_post_action 10:32:46 INFO - [mozharness: 2017-05-30 17:32:46.761246Z] Finished install step (success) 10:32:46 INFO - [mozharness: 2017-05-30 17:32:46.761391Z] Running setup-mitmproxy step. 10:32:46 INFO - Running pre-action listener: _resource_record_pre_action 10:32:46 INFO - Running main action method: setup_mitmproxy 10:32:46 INFO - Skipping: mitmproxy is not required 10:32:46 INFO - Running post-action listener: _resource_record_post_action 10:32:46 INFO - [mozharness: 2017-05-30 17:32:46.762144Z] Finished setup-mitmproxy step (success) 10:32:46 INFO - [mozharness: 2017-05-30 17:32:46.762286Z] Running run-tests step. 10:32:46 INFO - Running pre-action listener: _resource_record_pre_action 10:32:46 INFO - Running main action method: run_tests 10:32:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:32:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:32:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:32:46 INFO - 2017-05-30 10:32:46.776 screenresolution[2476:35430] starting screenresolution argv=screenresolution get 10:32:46 INFO - 2017-05-30 10:32:46.779 screenresolution[2476:35430] Display 0: 1600x1200x32@60 10:32:46 INFO - 2017-05-30 10:32:46.785 screenresolution[2477:35432] starting screenresolution argv=screenresolution list 10:32:46 INFO - Available Modes on Display 0 10:32:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:32:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:32:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:32:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:32:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:32:47 INFO - Intel Iris: 10:32:47 INFO - Chipset Model: Intel Iris 10:32:47 INFO - Type: GPU 10:32:47 INFO - Bus: Built-In 10:32:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:32:47 INFO - Vendor: Intel (0x8086) 10:32:47 INFO - Device ID: 0x0a2e 10:32:47 INFO - Revision ID: 0x0009 10:32:47 INFO - Displays: 10:32:47 INFO - Display: 10:32:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:32:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:32:47 INFO - Display Serial Number: 1600x1200 60 10:32:47 INFO - Main Display: Yes 10:32:47 INFO - Mirror: Off 10:32:47 INFO - Online: Yes 10:32:47 INFO - Rotation: Supported 10:32:47 INFO - Return code: 0 10:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:32:47 INFO - Python 2.7.3 10:32:47 INFO - Return code: 0 10:32:47 INFO - grabbing minidump binary from tooltool 10:32:47 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')]} 10:32:47 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 0x102254c60>, '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 0x101fe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102012be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:32:47 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 10:32:47 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 10:32:47 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 10:32:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:32:47 INFO - Return code: 0 10:32:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:32:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:32:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:32:47 INFO - ENV: RUST_BACKTRACE is now 1 10:32:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:32:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:32:47 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/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 10:32:47 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/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0113 --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 10:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XHmYWnefa8/Render', 10:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:47 INFO - 'HOME': '/Users/cltbld', 10:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:47 INFO - 'LOGNAME': 'cltbld', 10:32:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:32:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:47 INFO - 'MOZ_NO_REMOTE': '1', 10:32:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:32:47 INFO - 'NO_EM_RESTART': '1', 10:32:47 INFO - 'PAGER': '/bin/cat', 10:32:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:47 INFO - 'PWD': '/builds/slave/test', 10:32:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:47 INFO - 'RUST_BACKTRACE': '1', 10:32:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:32:47 INFO - 'SHELL': '/bin/bash', 10:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOw3TcFC8Y/Listeners', 10:32:47 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 10:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:47 INFO - 'USER': 'cltbld', 10:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:47 INFO - 'XPC_FLAGS': '0x0', 10:32:47 INFO - 'XPC_SERVICE_NAME': '0', 10:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:47 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/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 10:32:47 INFO - mozversion application_buildid: 20170530082023 10:32:47 INFO - mozversion application_changeset: 268cfff98df1cc643df7f79dab467043fd1837f1 10:32:47 INFO - mozversion application_display_name: Nightly 10:32:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:32:47 INFO - mozversion application_name: Firefox 10:32:47 INFO - mozversion application_remotingname: firefox 10:32:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:32:47 INFO - mozversion application_vendor: Mozilla 10:32:47 INFO - mozversion application_version: 55.0a1 10:32:47 INFO - mozversion platform_buildid: 20170530082023 10:32:47 INFO - mozversion platform_changeset: 268cfff98df1cc643df7f79dab467043fd1837f1 10:32:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:32:47 INFO - mozversion platform_version: 55.0a1 10:32:47 INFO - using testdate: 1496165567 10:32:47 INFO - actual date: 1496165567 10:32:47 INFO - starting webserver on 'localhost:58156' 10:32:47 INFO - SUITE-START | Running 2 tests 10:32:47 INFO - TEST-START | dromaeo_css 10:32:47 INFO - operating with platform_type : mac_ 10:32:47 INFO - Initialising browser for dromaeo_css test... 10:32:47 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58156/getInfo.html -foreground -profile /var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/tmpMknXkN/profile 10:32:47 INFO - TEST-INFO | started process 2487 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58156/getInfo.html -foreground) 10:33:21 INFO - TEST-INFO | 2487: killed by SIGTERM 10:33:21 INFO - Browser initialized. 10:33:21 INFO - Running cycle 1/1 for dromaeo_css test... 10:33:21 INFO - TEST-INFO | started process 2494 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/tmpMknXkN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:33:23 INFO - PID 2494 | 2017-05-30 10:33:23.787 plugin-container[2495:35939] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 10:33:23 INFO - PID 2494 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:33:23 INFO - PID 2494 | 2017-05-30 10:33:23.787 plugin-container[2495:35939] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 10:33:23 INFO - PID 2494 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:33:23 INFO - PID 2494 | 2017-05-30 10:33:23.787 plugin-container[2495:35939] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:33:24 INFO - PID 2494 | 2017-05-30 10:33:24.376 plugin-container[2496:35981] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 10:33:24 INFO - PID 2494 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:33:24 INFO - PID 2494 | 2017-05-30 10:33:24.376 plugin-container[2496:35981] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 10:33:24 INFO - PID 2494 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:33:24 INFO - PID 2494 | 2017-05-30 10:33:24.376 plugin-container[2496:35981] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:33:33 INFO - PID 2494 | RSS: Main: 261705728 10:33:33 INFO - PID 2494 | 10:36:36 INFO - PID 2494 | Cycle 1(1): loaded http://localhost:58156/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58156/tests/dromaeo/cssquery-ext.html) 10:36:37 INFO - PID 2494 | RSS: Main: 268009472 10:36:37 INFO - PID 2494 | 10:39:40 INFO - PID 2494 | Cycle 1(1): loaded http://localhost:58156/tests/dromaeo/cssquery-ext.html (next: http://localhost:58156/tests/dromaeo/cssquery-jquery.html) 10:39:40 INFO - PID 2494 | RSS: Main: 282079232 10:39:40 INFO - PID 2494 | 10:42:42 INFO - PID 2494 | Cycle 1(1): loaded http://localhost:58156/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58156/tests/dromaeo/cssquery-mootools.html) 10:42:43 INFO - PID 2494 | RSS: Main: 284110848 10:42:43 INFO - PID 2494 | 10:45:44 INFO - PID 2494 | Cycle 1(1): loaded http://localhost:58156/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58156/tests/dromaeo/cssquery-prototype.html) 10:45:45 INFO - PID 2494 | RSS: Main: 287772672 10:45:45 INFO - PID 2494 | 10:48:48 INFO - PID 2494 | Cycle 1(1): loaded http://localhost:58156/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58156/tests/dromaeo/cssquery-yui.html) 10:48:48 INFO - PID 2494 | RSS: Main: 287997952 10:48:48 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | Cycle 1(1): loaded http://localhost:58156/tests/dromaeo/cssquery-yui.html (next: http://localhost:58156/tests/dromaeo/cssquery-dojo.html) 10:51:50 INFO - PID 2494 | RSS: Main: 287875072 10:51:50 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | __start_tp_report 10:51:50 INFO - PID 2494 | _x_x_mozilla_page_load 10:51:50 INFO - PID 2494 | _x_x_mozilla_page_load_details 10:51:50 INFO - PID 2494 | |i|pagename|runs| 10:51:50 INFO - PID 2494 | |0;dojo.html;3562.1272788166903;3577.284543091381;3611.693006094482;3613.927721445571;3624.275144971005;6146.524834039832;6173.043178307364;6211.440970312677;6228.252609686839;6292.8741425171465;5149.922751158726;6164.383561643834;6199.628022318653;6277.497800175986;6292.937070629304;5253.816116435925;5441.673499590018;5474.315710536183;5488.725563721818;5519.9448005519835;4968;5527.060399732055;5605.355384130816;5630.605857589971;5635.408282130379;4688.5545873142;5222.294990176316;5406.783728650849;5436.401995780461;5485.50630443262;4387.495438024636;4881.731504767236;5509;5514.5864060195245;5515.696636684981;5401.945980540223;5508.228847961289;5526.778928842841;5530.944690553083;5576;6123.343628574248;6132.172156758825;6188.690565471708;6194.101855230966;6271.749130034793;6802.319768023208;6817.250102488722;6826.590404575742;6829.043933849265;6841.110655614738;6915.792526224222;6916.1700595928305;6924.23833378328;6927.618980956046;6929.89605155918;6227.906581401283;6303.086052522359;6316;6334.458196463915;6393.840153996094;7005.579665220103;7033.226345102034;7052.9647351762915;7056.576605403692;7057.89413158803;6390.936090639034;6507.446867016262;6523.934760652428;6597.274299827015;6645.568038077607;6793.45652347811;6856.6228857412325;6862.656867156622;6868.931310686829;6888.138982627172;6506.577072490273;6696.799096027127;6728.192616886005;6741.797746067626;6748.055272261888;6542.738290468423;6647.135872336566;6698.933010669928;6730.629815360202;6777.152855893013;5387.973060134753;5394.595405344615;5400.108982018001;5415.83752487426;5423.620346575702;4800.759962001886;4806.639502037291;4828.203346447796;4837.540433658867;4849.854504364875;6220.346863579259;6252.468540174136;6292.402221788922;6321.715522801393;6351.872962540723;6863.38229559342;6883.793486195422;6890;6893.138357705287;6913.89629155553;6267.247930248308;6355.650439225887;6359.300476947532;6378.936210637927;6403.359664033559;5788.479036886616;5830.096335068071;5898.852528686817;5947.881042379128;6118.6022908511695;6970.546914450544;7014.824629384204;7027.578345299298;7038.120234970629;7045.2954704531185;6856.828579285558;6872.931270687229;6873.48448866327;6879.277675843964;6896.586204827727;6789.422899053636;6874.312568743086;6962.268961758942;7041.3310735480045;7042.330978557029;6597.901031484435;6717.1267735194115;6751.459883209233;6782.626955517297;6789.72841086351;6799.3200679931615;6870.45036397097;6879.105716256855;6892.345227203407;6935.167779866449;6398.5201109916015;6432.871342573216;6519.934800652122;6582.17722784652;6635.966820165868;6619.437347825488;6646.069550263063;6652.800415987334;6664.933350666432;6669.6331701754925;6385.521085918482;6449.258335291313;6477.870442591027;6479;6506.772262970773;6408.61548307103;6465.77369792055;6562.50781191403;6648.301928297459;6674.299198584273;5574.247476590608;6325.778597749057;6370.8088757337355;6430.067640192239;6456.225252969673 10:51:50 INFO - PID 2494 | |1;ext.html;656.4551422319475;764.7514557768726;807.8384323135373;1158.4265788434725;1245.053759143051;29637.703622963792;39877.80061099694;39943.600563994354;40021;40146;39852.80073599635;40065.59934400655;40160.99999999993;40236;40484.78545643628;7986.281234688877;8184.713535026275;8458.323334133254;8656.48061116335;8657.870131948026;8467.110953349902;8548.273396761282;8569.700060497884;8585.01272353678;8608.52653104079;8536;8543.316534677211;8560.48637081774;8576.528290943996;8586.31309495242;8515.191056849651;8524.445911015764;8526.872096918582;8529.104444033412;8570.528620925847;8329.208725171098;8351.874721879176;8386.8322633547;8392.412531122824;8394.958025209897;37257.44113838294;37488.43767343492;37552.24895502102;37680.6811761587;37713.81143094267;3597.6402359764074;3639.7452178347526;3647.270545890806;3647.5622925248877;3652.5616925969052;30479.39041219163;30669.8466507666;30791.538126928113;30825.921092761644;30858.074257772303;3093.8143711377165;3128.6871312868757;3131.264152924061;3140.952885706716;3151.11768704763;241550;242304;283064.99999999796;301258.49370753224;301576;48218.75890620524;48356.27465588019;48431.78920527015;48501.5149848497;48643.5135648646;50313.74843125761;50406;50415.747921260896;50513.74743126261;50590.98818023619;13103.410346534429;13296.867031329562;13307.135036222615;13311.66720831987;13360.06479546442;10899.727506812393;11007.119430445546;11012.559497620166;11033.951774581563;11049.613263535737;13952.651183720489;13968.580942571536;13988.300584970711;14065.226412547203;14126.223057731719;6093.329733729286;6097.420745029215;6146.108814222002;6148.800983808204;6171.352008039091;885.291766586728;936.513013233115;1027.106417416852;1046.7487802927242;1054.5570860238718;20890;23564.64653030206;24424;24571.186313873022;24604.876975615007;1081.7836432713332;1092.4974511724695;1098.9395583242838;1122.7866705325962;1128.1877048525048;11986.400679965967;12615;12683.619491415087;12709.555165569162;13002.674933126747;289639.55180224386;290174;290605;290921;291395;53416.198757018676;53566.464335356926;53738.46261537413;53778.46221537813;53839.46160538345;1486.3831509923477;1495.2150121186464;1527.9312430940633;1529.2353823088456;1530.3419529602377;23272.65091023602;24093.879530602233;24333;24361.512769744153;24405.877970610745;37830.62169378271;37896.81051594725;37929.62070379261;45751.77124114358;50030.24954625756;280027;281708.5914570414;282347.17701818293;282439;284611;34452.65547344595;35996.82001589975;36043.81978090198;36120.916372508866;36129.91610251697;44365.77817111023;44654.553454465036;45864.77067614641;45960.31059534043;46264.53735462602;29942.550861737527;30742.077737667907;31317.373652526367;31448.056558303288;32056.83971580127;27999.0200342987;30450.086497405115;30737.538936915516;31016.0695179145;31405.528917065807;36848.81575592105;37790.811045944596;37879.62120378761;37911;37963.43054854124;29176.27059323534;30710.692893070784;30867.845660771553;31509.8424507876;31735.84132079325;23759.812009399808;24078;24114.397140070938;24185.63721544143;24403.26790196297 10:51:50 INFO - PID 2494 | |2;jquery.html;424.77452549394985;426.1817310763334;432.21984318305454;442.92248856450135;456.96801223914326;19053.904730476363;20920.37238882832;21679.024443900023;21829.345119646438;21952;22239.22162724305;23124.537509249803;23345.649815252786;23413.297601071994;23414.946327415266;5887.411258874111;6192.380761923817;6198.78304259351;6199.349068347826;6199.876002479948;5909.940900590982;5982.042873140298;6029.366916473773;6050.546209034318;6139.109829074781;5943.108533719934;5982.551308651847;5989.850253743648;6002.709417475242;6013.368596297391;5687.601867869251;5965;5973.970130149343;5992.43071908168;5993.580449368546;5351.25082488452;5360.463953604647;5361.544268737162;5363.490468405535;5382.434844341366;27527.724722752915;28184.436311273865;28210.012649557168;28246.858765706245;28257;2106.2101711858054;2159.6976423300753;2164.4805246740825;2167.100653228908;2170.6526955687013;16124.919375403048;17831.821681783276;18164.45506634803;18421.447356579323;18430.723539146922;2115.122224276923;2140.550484398278;2171.6742488626674;2173.3154056472317;2174.1955476473804;140987;157106;162886;164609;175196;11734.295942243492;12564.371781411075;12932.03009774252;12969.76787205214;13001.219926804233;16414.835851641332;16492.010479371038;16513.83486165123;16524.83475165257;16541.338346466007;8572.914270857336;8753.299736021103;8769.956150219208;8826.205641492168;8836.425632333878;8159.67361305553;8258.752237432887;8279.62741676614;8391.286740626992;8410.803894520166;7792.844143117106;7899.131095579482;7937.682492700343;7968.645330293864;7989.640466179036;2106.4733816545863;2112.7570329412006;2119.4807272218404;2124.7662757096705;2284.7372552156717;898.7797989492485;969.4958621516913;991.8908920018937;994.7171241999483;1014.5434554450527;20532.486687832625;20914;21490.57018859614;21516.569668606855;21579.568408632054;994.4381424495137;1004.6785028790863;1006.9798604028025;1014.4826138669332;1044.8506642693128;8997.730068097968;10653.094486968539;10941.890581094247;10982.231243812856;11016.724581885357;508469.3729594059;571091;577580;581974;582158;99622;100757.49621251994;117516;136336;137126.31436842753;1067.1889364083197;1163.8836116388463;1169.9941500292443;1214.107630891294;1252.9812052819216;21858.562828743656;22892.4276893079;23036.654450182927;23076.769232307463;23222.767772322062;56265.71867140638;58329;58444.415555843894;59052.704736476044;59343.40656593552;78328.60835695785;78728.60635696785;78943.60528197323;79248.60375698084;79770;8860.335474839541;8991.190792828676;9011.459312441275;9099.54502274897;9128;11851.466683999068;13783.37974791157;13928.164310141425;14244.287785610884;14293.927955403595;8609.311255099703;9241.260699144186;11743.354115523729;11811.822822657496;11966.760664786481;11158.776824463628;11373;11400.144989125683;11522.654320370402;11631.011364034151;11600.88399115998;11743.060555155738;11828.526858925547;12015.798420157915;12174.939125304316;8643.222110010129;8747.562621869009;9016.594253258489;9056.094390560891;9109.407888487493;11326.37704926237;11779.469923853276;12058.336791476553;12116.394180290774;12147.817782733442 10:51:50 INFO - PID 2494 | |3;mootools.html;1215.2769102384082;1452.5569701241118;1473.3075454536365;1481.9996502360905;1487.8512148785119;11381.430928453576;11611.013063889579;11771.646850594496;11795.882041179586;11983.880161198385;11641.476133573988;11716.589919352804;11816.645500634973;11830.29018258903;11849.703757406069;2511.2591785423297;2539.149384956042;2552.629868669042;2553.195743340851;2560.886014583662;2481.305234534333;2512.0705339024494;2517.496500699858;2517.8992840286364;2520.407704189514;2456.115798312606;2474.134053081416;2475.9876200618974;2486.2168416948693;2487.502499500098;2377.441301294203;2408.434018005767;2409.9277021689377;2410;2418.5404773093055;2400.7479214682558;2406.049610403898;2406.759324067597;2412.8069754419603;2503.2240005598323;11343.829842552368;11438.656840294805;11444.94277528607;11449.942750286278;11479.426028698532;6206.379362063757;6433.420992110732;6466.385693359123;6474.384933431316;6484.902726459107;3785.3754130568423;3994.0613955720673;4163.500379954395;4249.5113061997645;4249.532551419341;1247.5072346423112;1247.8689737577515;1262.072376803049;1262.873712628739;1279.32195936154;14970.17664028478;15461.072335659896;16022.19889005545;16099.91950040254;16101.43644972432;5387.353517577837;5462.890742185134;5531.585131115183;5585.218069470277;5594.524465420485;11625.825612615818;11702.180847340604;11710.648680539598;11712.824307635392;11743.823842642367;6195.380461953769;6331.9366806331345;6391.744330226739;6404.423601875854;6422.229332480132;5651.717414129277;5680.715964201773;6087.817365478954;6102.7558897644485;6131.202943617302;4328.285832837547;4404.66964977628;4486.394336764561;4502.414686090788;4527.547245275512;1244.4710997825887;1252.8308678328308;1264.1909178126007;1267.3789842976876;1275.9808602870964;671.8253254153955;809.465752603279;813.2761841960541;813.7029984055777;824.2911096457042;5502.174673798882;6186.536009799283;6248.4064013918605;6345.302016778151;6354.3645635437015;528.0231571592523;563.4027930393796;590.7814108779779;601.4316470935012;602.0968547179231;3804;4046.7167298288837;4123.309637874086;4274.722143060691;4328.978355108205;14236.572902812932;14633.195174265518;14974.625634359229;14985.775213371808;14988;11896.464659090361;14261.500847470288;14728.484503042342;14868.959172858013;14895;8378.539180345018;8422.6209820557;8559.486430814171;8582.369349823639;8603.698870539502;7007.369336759716;7158.534695244793;7302.415806735555;7338.889916651361;7348.522346047704;13381.866181338062;13468.93265533666;13510.662233444242;13525.256110913993;13638.863611364157;13870.930645346709;13955;14029.298535073409;14042.297885105909;14242.928785356007;9300.860802015439;9452.763680908032;9465.05349465048;9467.573959171992;9505.572249248939;10516.631917882838;10620.893791062299;10756.139508839431;10799.730006749895;11030.393328366696;8928.241099506615;9166.129217724305;9226.35415520907;9227.261819054595;9282.72151835446;8875.73372798817;8980.86528702057;9037.68368107113;9057.637694492147;9138.360314777901;8169.0605580359015;8314;8327.250547450758;8341.791455213668;8345.707900223462;5007.874803129951;5641.887162256649;5710.114932185518;5752.827415177551;5828.184054232326;6086.69566521681;6203.31763506005;6205.968970155301;6224.408681175281;6285.151504546828 10:51:50 INFO - PID 2494 | |4;prototype.html;336.6498841205147;386.8278616015873;392.6270043458715;394.5245978595792;405.8680928698172;5907.911381329278;6283.183186185795;6286.528510361718;6301.810945671626;6349.80950571483;5336.759845806938;5977.252843394575;6125.387461253884;6167.660778657172;6193.597416167946;4065.3901914712883;4667.346571479987;4683.69555978862;4696.43642762869;4731.7634118293945;3873.2447172801317;4662.813487460515;4683.789229484664;4690.882727931795;4743.952560474386;4505.662075344346;4525.230710779176;4655.185342565054;4655.278431843053;4660.464046634646;3902.9609703903025;4638.976805115986;4641.955559999007;4652.790624421909;4734.431868175807;3573.428251479777;4090.734102283342;4094.8566800161852;4101.118259574206;4115.156392939471;5930.458080898998;5985;6003.219581454427;6016.548758843104;6029.547783916224;4945.802167913279;5555.333359996826;5563;5585.374873809303;5591.860203494904;5102;6357.523185761086;6604.966975165094;6691.19705635322;6763.898541521881;3513.1568423578155;4386.056997745468;4386.947132688132;4408.294672852328;4409.757463339515;5640.251522028185;6569.967150164219;6895.138107736583;7140.3573678368675;7192.784216473514;4028.879133625996;4929.679570827885;5023.6985780853265;5053.54518093366;5087.4403815581;4111.342185250345;4672.32251323563;4982.829035176731;5031.371078615173;5070.188769796889;5291.973540132275;5612.242347283064;5661.207430959669;5671.886562268732;5737.3688894221605;6190.288116866528;6376.29860715321;6400.487960963112;6749;6768.661566921634;1567.529741077672;1690.3661127077346;1749.9387521436688;1770.8224031019229;1776.253973331204;1210.0863847794858;1217.8843009914044;1244.4337826289018;1250.2186133666457;1259.9370031498388;5275.314209152785;5734.111212762028;6231.626102433868;6274.372562743689;6366.395192456709;6882.8967565486555;6961.79114626562;6968.3728464438445;6987.685554150075;7008.84354081581;5638.323401191801;6031.884101441247;6142.293636231801;6180.227471566054;6272.020790124654;3046.9238269043453;3261.972478669212;3630.2920930418845;3630.8910732678064;3636.9636303637153;6535.901961470582;6961.825954351182;7027.754028608974;7110.8978108393285;7214.783556493314;7120.572765634079;7122.358987691133;7171.247019063027;7206.279372062857;7273.290776667584;6347.857385670623;6730.730770769114;6947.786962435906;7148.749793757298;7169.88488395914;5889.646621202742;6900.964855271749;6953.79138625842;6967.477439191979;6994.930050699428;5783.8554036149435;6098.786542470993;6204.81385558434;6319.96840015797;6872.62200578973;5841.678707671119;7025.473089518205;7177.210506844347;7235.312645298688;7241.601711905896;4239.406483092308;4440.134173836071;4484.946037681077;4492.932606010978;4499.932501012422;6648.301928297459;6860.176778786378;7025.243689077731;7071.418932089853;7156.892646610409;6018.699065046642;6356.745730170926;6509.81567510876;6751.696173672099;6757.391834734701;6598.703058362481;6651.503411732515;6692;6747.6626168692355;6782.728690852311;5398.78404863801;6286.214223222098;6602.933970660232;6620.040094186413;6653.600783952978;6048.576599637983;6596.70314835824;6766.391024807791;6791.7095751807;6877.312268773083;5293.867653308698;5501.312335958005;6104.542159338156;6504.544681872224;6529.934700652932 10:51:50 INFO - PID 2494 | |5;yui.html;728.6684558525872;778.5250996891898;820.7742870710557;835.5070508400047;868.1882439918675;14446.855531444695;16736.41422550211;17439.302427902883;19315.517112072204;19958;18208;18749.812501874978;18865.056747162656;18959.715604265948;19028.524286892836;983.4443539400233;995.149147478906;997.7655251016042;997.7755005123826;1003.3027046202856;987.2151639446621;994.2195376629347;994.5773046455228;994.9651762188325;995.3082607587729;1054.96307629233;1068.4070340960764;1068.9732756681067;1069.4652673663168;1070.2133931560336;1045.9529321180564;1053.789242151569;1066.0086119908444;1066.296244478648;1066.5946940162728;682.1439093937126;758.416019664855;761.6708843068881;768.1511929318135;781.0666253826679;17273.395431159974;17464.65070698579;17726.645467090588;18079.276828926828;18259.087045647666;17581.56046098845;17648.294068237254;17706.645867082585;17990.1004949752;18010.549736256566;14561.563153105424;14793.778093328609;14943.476978305707;14954.62613434662;15001.924990375088;3579.391503444421;3640;3641.6904563111902;3649.5802982656805;3650.2152037312107;128354;155401.89196216126;160101;162844;163318;13567;13705.177689338672;13823.447062117604;13860.861391386159;13879.167249965034;22342;25488.490230195293;26491.735082649313;26883.193504194904;27216.319592010357;7890.8816367754525;7919.920800791919;7967.28294453502;7979.72070977513;7991.160928102582;3892.649661530476;3972.5828787977425;4005.0187704012706;4016.6987475938836;4027.368915589191;4238.3006803877015;4298.570142985676;4332.805023773938;4338.6312163466455;4341.479022517255;779.2402407652494;820.6553247635887;823.9423240373117;862.3446180902433;864.8356812205661;638.4317957018258;656.5929123943185;664.4385494257344;672.6334147889412;681.9147606549182;11006.064484518745;11152.832707509393;11591.24656897299;11675.941620291844;12144.392780360946;619.3837132053635;644.5649186798986;648.5622205011598;670.6412069542771;673.7338751193251;5385.138377859602;5623.297087864017;5801.042827933428;6341.746330146835;6395.52033597482;132759.67240327667;142368.5763142376;145225;146193.5380646201;147500.26249868682;100720;102232.48883755534;103506;113706;115721;77558.6122069386;82714.17285827185;101857.49071254744;104273.47863260788;104978.47510762398;10929;11724.472398741906;12215.572454964034;12654.987600992083;12712.491500340253;42886.35670464883;42945.785271073444;43409.56590434181;43543.78228108966;44710.77644611756;59170.11244831245;59399.70300148472;59511;59715.40284597098;60189.69905150446;14893.925530372278;16442.588935276715;17069.57326066858;17283.271672833118;17287.82712172862;13001;15452.227388630748;15591.220438978233;15709.921450393133;15754.842451575338;17176.82823171752;17341.479755607354;17601.735973960145;17989.460316190536;18069.909650451664;14381.496647617283;15121.017133886262;16721.91639041797;17097;17614.943103413836;16244.350225990827;16555.66888662244;16928.66142677115;17116.972981621144;17241.91379043097;11878;12088;12179.269243845398;12300.200486968677;12342.382880855741;12139.150259481752;13051.543195988095;13058.608241752383;13160.539381121614;13332.666683332995 10:51:50 INFO - PID 2494 | __end_tp_report 10:51:50 INFO - PID 2494 | __start_cc_report 10:51:50 INFO - PID 2494 | _x_x_mozilla_cycle_collect,455 10:51:50 INFO - PID 2494 | __end_cc_report 10:51:50 INFO - PID 2494 | __startTimestamp1496166710767__endTimestamp 10:51:50 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | ------- Summary: start ------- 10:51:50 INFO - PID 2494 | Number of tests: 6 10:51:50 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | [#0] dojo.html Cycles:165 Average:6218.49 Median:6430.07 stddev:776.61 (12.1%) stddev-sans-first:750.51 10:51:50 INFO - PID 2494 | Values: 3562.1 3577.3 3611.7 3613.9 3624.3 6146.5 6173.0 6211.4 6228.3 6292.9 5149.9 6164.4 6199.6 6277.5 6292.9 5253.8 5441.7 5474.3 5488.7 5519.9 4968.0 5527.1 5605.4 5630.6 5635.4 4688.6 5222.3 5406.8 5436.4 5485.5 4387.5 4881.7 5509.0 5514.6 5515.7 5401.9 5508.2 5526.8 5530.9 5576.0 6123.3 6132.2 6188.7 6194.1 6271.7 6802.3 6817.3 6826.6 6829.0 6841.1 6915.8 6916.2 6924.2 6927.6 6929.9 6227.9 6303.1 6316.0 6334.5 6393.8 7005.6 7033.2 7053.0 7056.6 7057.9 6390.9 6507.4 6523.9 6597.3 6645.6 6793.5 6856.6 6862.7 6868.9 6888.1 6506.6 6696.8 6728.2 6741.8 6748.1 6542.7 6647.1 6698.9 6730.6 6777.2 5388.0 5394.6 5400.1 5415.8 5423.6 4800.8 4806.6 4828.2 4837.5 4849.9 6220.3 6252.5 6292.4 6321.7 6351.9 6863.4 6883.8 6890.0 6893.1 6913.9 6267.2 6355.7 6359.3 6378.9 6403.4 5788.5 5830.1 5898.9 5947.9 6118.6 6970.5 7014.8 7027.6 7038.1 7045.3 6856.8 6872.9 6873.5 6879.3 6896.6 6789.4 6874.3 6962.3 7041.3 7042.3 6597.9 6717.1 6751.5 6782.6 6789.7 6799.3 6870.5 6879.1 6892.3 6935.2 6398.5 6432.9 6519.9 6582.2 6636.0 6619.4 6646.1 6652.8 6664.9 6669.6 6385.5 6449.3 6477.9 6479.0 6506.8 6408.6 6465.8 6562.5 6648.3 6674.3 5574.2 6325.8 6370.8 6430.1 6456.2 10:51:50 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | [#1] ext.html Cycles:180 Average:43920.28 Median:33254.75 stddev:73950.51 (222.4%) stddev-sans-first:74086.62 10:51:50 INFO - PID 2494 | Values: 656.5 764.8 807.8 1158.4 1245.1 29637.7 39877.8 39943.6 40021.0 40146.0 39852.8 40065.6 40161.0 40236.0 40484.8 7986.3 8184.7 8458.3 8656.5 8657.9 8467.1 8548.3 8569.7 8585.0 8608.5 8536.0 8543.3 8560.5 8576.5 8586.3 8515.2 8524.4 8526.9 8529.1 8570.5 8329.2 8351.9 8386.8 8392.4 8395.0 37257.4 37488.4 37552.2 37680.7 37713.8 3597.6 3639.7 3647.3 3647.6 3652.6 30479.4 30669.8 30791.5 30825.9 30858.1 3093.8 3128.7 3131.3 3141.0 3151.1 241550.0 242304.0 283065.0 301258.5 301576.0 48218.8 48356.3 48431.8 48501.5 48643.5 50313.7 50406.0 50415.7 50513.7 50591.0 13103.4 13296.9 13307.1 13311.7 13360.1 10899.7 11007.1 11012.6 11034.0 11049.6 13952.7 13968.6 13988.3 14065.2 14126.2 6093.3 6097.4 6146.1 6148.8 6171.4 885.3 936.5 1027.1 1046.7 1054.6 20890.0 23564.6 24424.0 24571.2 24604.9 1081.8 1092.5 1098.9 1122.8 1128.2 11986.4 12615.0 12683.6 12709.6 13002.7 289639.6 290174.0 290605.0 290921.0 291395.0 53416.2 53566.5 53738.5 53778.5 53839.5 1486.4 1495.2 1527.9 1529.2 1530.3 23272.7 24093.9 24333.0 24361.5 24405.9 37830.6 37896.8 37929.6 45751.8 50030.2 280027.0 281708.6 282347.2 282439.0 284611.0 34452.7 35996.8 36043.8 36120.9 36129.9 44365.8 44654.6 45864.8 45960.3 46264.5 29942.6 30742.1 31317.4 31448.1 32056.8 27999.0 30450.1 30737.5 31016.1 31405.5 36848.8 37790.8 37879.6 37911.0 37963.4 29176.3 30710.7 30867.8 31509.8 31735.8 23759.8 24078.0 24114.4 24185.6 24403.3 10:51:50 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | [#2] jquery.html Cycles:180 Average:35988.51 Median:23100.65 stddev:95586.82 (413.8%) stddev-sans-first:95817.67 10:51:50 INFO - PID 2494 | Values: 424.8 426.2 432.2 442.9 457.0 19053.9 20920.4 21679.0 21829.3 21952.0 22239.2 23124.5 23345.6 23413.3 23414.9 5887.4 6192.4 6198.8 6199.3 6199.9 5909.9 5982.0 6029.4 6050.5 6139.1 5943.1 5982.6 5989.9 6002.7 6013.4 5687.6 5965.0 5974.0 5992.4 5993.6 5351.3 5360.5 5361.5 5363.5 5382.4 27527.7 28184.4 28210.0 28246.9 28257.0 2106.2 2159.7 2164.5 2167.1 2170.7 16124.9 17831.8 18164.5 18421.4 18430.7 2115.1 2140.6 2171.7 2173.3 2174.2 140987.0 157106.0 162886.0 164609.0 175196.0 11734.3 12564.4 12932.0 12969.8 13001.2 16414.8 16492.0 16513.8 16524.8 16541.3 8572.9 8753.3 8770.0 8826.2 8836.4 8159.7 8258.8 8279.6 8391.3 8410.8 7792.8 7899.1 7937.7 7968.6 7989.6 2106.5 2112.8 2119.5 2124.8 2284.7 898.8 969.5 991.9 994.7 1014.5 20532.5 20914.0 21490.6 21516.6 21579.6 994.4 1004.7 1007.0 1014.5 1044.9 8997.7 10653.1 10941.9 10982.2 11016.7 508469.4 571091.0 577580.0 581974.0 582158.0 99622.0 100757.5 117516.0 136336.0 137126.3 1067.2 1163.9 1170.0 1214.1 1253.0 21858.6 22892.4 23036.7 23076.8 23222.8 56265.7 58329.0 58444.4 59052.7 59343.4 78328.6 78728.6 78943.6 79248.6 79770.0 8860.3 8991.2 9011.5 9099.5 9128.0 11851.5 13783.4 13928.2 14244.3 14293.9 8609.3 9241.3 11743.4 11811.8 11966.8 11158.8 11373.0 11400.1 11522.7 11631.0 11600.9 11743.1 11828.5 12015.8 12174.9 8643.2 8747.6 9016.6 9056.1 9109.4 11326.4 11779.5 12058.3 12116.4 12147.8 10:51:50 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | [#3] mootools.html Cycles:180 Average:7044.95 Median:3794.69 stddev:4455.53 (117.4%) stddev-sans-first:4446.49 10:51:50 INFO - PID 2494 | Values: 1215.3 1452.6 1473.3 1482.0 1487.9 11381.4 11611.0 11771.6 11795.9 11983.9 11641.5 11716.6 11816.6 11830.3 11849.7 2511.3 2539.1 2552.6 2553.2 2560.9 2481.3 2512.1 2517.5 2517.9 2520.4 2456.1 2474.1 2476.0 2486.2 2487.5 2377.4 2408.4 2409.9 2410.0 2418.5 2400.7 2406.0 2406.8 2412.8 2503.2 11343.8 11438.7 11444.9 11449.9 11479.4 6206.4 6433.4 6466.4 6474.4 6484.9 3785.4 3994.1 4163.5 4249.5 4249.5 1247.5 1247.9 1262.1 1262.9 1279.3 14970.2 15461.1 16022.2 16099.9 16101.4 5387.4 5462.9 5531.6 5585.2 5594.5 11625.8 11702.2 11710.6 11712.8 11743.8 6195.4 6331.9 6391.7 6404.4 6422.2 5651.7 5680.7 6087.8 6102.8 6131.2 4328.3 4404.7 4486.4 4502.4 4527.5 1244.5 1252.8 1264.2 1267.4 1276.0 671.8 809.5 813.3 813.7 824.3 5502.2 6186.5 6248.4 6345.3 6354.4 528.0 563.4 590.8 601.4 602.1 3804.0 4046.7 4123.3 4274.7 4329.0 14236.6 14633.2 14974.6 14985.8 14988.0 11896.5 14261.5 14728.5 14869.0 14895.0 8378.5 8422.6 8559.5 8582.4 8603.7 7007.4 7158.5 7302.4 7338.9 7348.5 13381.9 13468.9 13510.7 13525.3 13638.9 13870.9 13955.0 14029.3 14042.3 14242.9 9300.9 9452.8 9465.1 9467.6 9505.6 10516.6 10620.9 10756.1 10799.7 11030.4 8928.2 9166.1 9226.4 9227.3 9282.7 8875.7 8980.9 9037.7 9057.6 9138.4 8169.1 8314.0 8327.3 8341.8 8345.7 5007.9 5641.9 5710.1 5752.8 5828.2 6086.7 6203.3 6206.0 6224.4 6285.2 10:51:50 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | [#4] prototype.html Cycles:180 Average:5413.75 Median:5994.11 stddev:1671.52 (27.9%) stddev-sans-first:1632.19 10:51:50 INFO - PID 2494 | Values: 336.6 386.8 392.6 394.5 405.9 5907.9 6283.2 6286.5 6301.8 6349.8 5336.8 5977.3 6125.4 6167.7 6193.6 4065.4 4667.3 4683.7 4696.4 4731.8 3873.2 4662.8 4683.8 4690.9 4744.0 4505.7 4525.2 4655.2 4655.3 4660.5 3903.0 4639.0 4642.0 4652.8 4734.4 3573.4 4090.7 4094.9 4101.1 4115.2 5930.5 5985.0 6003.2 6016.5 6029.5 4945.8 5555.3 5563.0 5585.4 5591.9 5102.0 6357.5 6605.0 6691.2 6763.9 3513.2 4386.1 4386.9 4408.3 4409.8 5640.3 6570.0 6895.1 7140.4 7192.8 4028.9 4929.7 5023.7 5053.5 5087.4 4111.3 4672.3 4982.8 5031.4 5070.2 5292.0 5612.2 5661.2 5671.9 5737.4 6190.3 6376.3 6400.5 6749.0 6768.7 1567.5 1690.4 1749.9 1770.8 1776.3 1210.1 1217.9 1244.4 1250.2 1259.9 5275.3 5734.1 6231.6 6274.4 6366.4 6882.9 6961.8 6968.4 6987.7 7008.8 5638.3 6031.9 6142.3 6180.2 6272.0 3046.9 3262.0 3630.3 3630.9 3637.0 6535.9 6961.8 7027.8 7110.9 7214.8 7120.6 7122.4 7171.2 7206.3 7273.3 6347.9 6730.7 6947.8 7148.7 7169.9 5889.6 6901.0 6953.8 6967.5 6994.9 5783.9 6098.8 6204.8 6320.0 6872.6 5841.7 7025.5 7177.2 7235.3 7241.6 4239.4 4440.1 4484.9 4492.9 4499.9 6648.3 6860.2 7025.2 7071.4 7156.9 6018.7 6356.7 6509.8 6751.7 6757.4 6598.7 6651.5 6692.0 6747.7 6782.7 5398.8 6286.2 6602.9 6620.0 6653.6 6048.6 6596.7 6766.4 6791.7 6877.3 5293.9 5501.3 6104.5 6504.5 6529.9 10:51:50 INFO - PID 2494 | 10:51:50 INFO - PID 2494 | [#5] yui.html Cycles:180 Average:24782.24 Median:17858.05 stddev:38359.49 (214.8%) stddev-sans-first:38424.59 10:51:50 INFO - PID 2494 | Values: 728.7 778.5 820.8 835.5 868.2 14446.9 16736.4 17439.3 19315.5 19958.0 18208.0 18749.8 18865.1 18959.7 19028.5 983.4 995.1 997.8 997.8 1003.3 987.2 994.2 994.6 995.0 995.3 1055.0 1068.4 1069.0 1069.5 1070.2 1046.0 1053.8 1066.0 1066.3 1066.6 682.1 758.4 761.7 768.2 781.1 17273.4 17464.7 17726.6 18079.3 18259.1 17581.6 17648.3 17706.6 17990.1 18010.5 14561.6 14793.8 14943.5 14954.6 15001.9 3579.4 3640.0 3641.7 3649.6 3650.2 128354.0 155401.9 160101.0 162844.0 163318.0 13567.0 13705.2 13823.4 13860.9 13879.2 22342.0 25488.5 26491.7 26883.2 27216.3 7890.9 7919.9 7967.3 7979.7 7991.2 3892.6 3972.6 4005.0 4016.7 4027.4 4238.3 4298.6 4332.8 4338.6 4341.5 779.2 820.7 823.9 862.3 864.8 638.4 656.6 664.4 672.6 681.9 11006.1 11152.8 11591.2 11675.9 12144.4 619.4 644.6 648.6 670.6 673.7 5385.1 5623.3 5801.0 6341.7 6395.5 132759.7 142368.6 145225.0 146193.5 147500.3 100720.0 102232.5 103506.0 113706.0 115721.0 77558.6 82714.2 101857.5 104273.5 104978.5 10929.0 11724.5 12215.6 12655.0 12712.5 42886.4 42945.8 43409.6 43543.8 44710.8 59170.1 59399.7 59511.0 59715.4 60189.7 14893.9 16442.6 17069.6 17283.3 17287.8 13001.0 15452.2 15591.2 15709.9 15754.8 17176.8 17341.5 17601.7 17989.5 18069.9 14381.5 15121.0 16721.9 17097.0 17614.9 16244.4 16555.7 16928.7 17117.0 17241.9 11878.0 12088.0 12179.3 12300.2 12342.4 12139.2 13051.5 13058.6 13160.5 13332.7 10:51:50 INFO - PID 2494 | -------- Summary: end -------- 10:51:50 INFO - PID 2494 | 10:51:51 INFO - TEST-INFO | 2494: exit 0 10:51:51 INFO - TEST-OK | dromaeo_css | took 1143922ms 10:51:51 INFO - TEST-START | kraken 10:51:51 INFO - operating with platform_type : mac_ 10:51:51 INFO - Initialising browser for kraken test... 10:51:51 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58156/getInfo.html -foreground -profile /var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/tmpLQQiXt/profile 10:51:51 INFO - TEST-INFO | started process 2500 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58156/getInfo.html -foreground) 10:52:25 INFO - TEST-INFO | 2500: killed by SIGTERM 10:52:25 INFO - Browser initialized. 10:52:25 INFO - Running cycle 1/1 for kraken test... 10:52:25 INFO - TEST-INFO | started process 2505 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/tmpLQQiXt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:52:27 INFO - PID 2505 | 2017-05-30 10:52:27.465 plugin-container[2506:38302] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984f, name = 'com.apple.tsm.portname' 10:52:27 INFO - PID 2505 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:52:27 INFO - PID 2505 | 2017-05-30 10:52:27.465 plugin-container[2506:38302] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 10:52:27 INFO - PID 2505 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:52:27 INFO - PID 2505 | 2017-05-30 10:52:27.465 plugin-container[2506:38302] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:52:28 INFO - PID 2505 | 2017-05-30 10:52:28.063 plugin-container[2507:38343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 10:52:28 INFO - PID 2505 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:52:28 INFO - PID 2505 | 2017-05-30 10:52:28.063 plugin-container[2507:38343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9623, name = 'com.apple.CFPasteboardClient' 10:52:28 INFO - PID 2505 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:52:28 INFO - PID 2505 | 2017-05-30 10:52:28.063 plugin-container[2507:38343] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:52:37 INFO - PID 2505 | RSS: Main: 262279168 10:52:37 INFO - PID 2505 | 10:52:40 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58156/tests/kraken/driver.html?testName=audio-beat-detection) 10:52:41 INFO - PID 2505 | RSS: Main: 271851520 10:52:41 INFO - PID 2505 | 10:52:43 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58156/tests/kraken/driver.html?testName=audio-dft) 10:52:44 INFO - PID 2505 | RSS: Main: 279994368 10:52:44 INFO - PID 2505 | 10:52:48 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58156/tests/kraken/driver.html?testName=audio-fft) 10:52:48 INFO - PID 2505 | RSS: Main: 282288128 10:52:48 INFO - PID 2505 | 10:52:50 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58156/tests/kraken/driver.html?testName=audio-oscillator) 10:52:51 INFO - PID 2505 | RSS: Main: 282992640 10:52:51 INFO - PID 2505 | 10:52:53 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58156/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:52:53 INFO - PID 2505 | RSS: Main: 283983872 10:52:53 INFO - PID 2505 | 10:52:56 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58156/tests/kraken/driver.html?testName=imaging-darkroom) 10:52:57 INFO - PID 2505 | RSS: Main: 293617664 10:52:57 INFO - PID 2505 | 10:53:00 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58156/tests/kraken/driver.html?testName=imaging-desaturate) 10:53:01 INFO - PID 2505 | RSS: Main: 291950592 10:53:01 INFO - PID 2505 | 10:53:04 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58156/tests/kraken/driver.html?testName=json-parse-financial) 10:53:04 INFO - PID 2505 | RSS: Main: 290889728 10:53:04 INFO - PID 2505 | 10:53:06 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58156/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:53:06 INFO - PID 2505 | RSS: Main: 290705408 10:53:06 INFO - PID 2505 | 10:53:08 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58156/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:53:09 INFO - PID 2505 | RSS: Main: 290480128 10:53:09 INFO - PID 2505 | 10:53:11 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58156/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:53:12 INFO - PID 2505 | RSS: Main: 290676736 10:53:12 INFO - PID 2505 | 10:53:15 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:53:16 INFO - PID 2505 | RSS: Main: 290512896 10:53:16 INFO - PID 2505 | 10:53:19 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:53:19 INFO - PID 2505 | RSS: Main: 290672640 10:53:19 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | Cycle 1(1): loaded http://localhost:58156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58156/tests/kraken/driver.html?testName=ai-astar) 10:53:21 INFO - PID 2505 | RSS: Main: 292544512 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | __start_tp_report 10:53:21 INFO - PID 2505 | _x_x_mozilla_page_load 10:53:21 INFO - PID 2505 | _x_x_mozilla_page_load_details 10:53:21 INFO - PID 2505 | |i|pagename|runs| 10:53:21 INFO - PID 2505 | |0;ai-astar;105;116;115;114;110;170;151;131;154;94 10:53:21 INFO - PID 2505 | |1;audio-beat-detection;138;112;122;184;97;112;144;100;157;131 10:53:21 INFO - PID 2505 | |2;audio-dft;202;211;220;226;244;270;204;202;197;203 10:53:21 INFO - PID 2505 | |3;audio-fft;86;59;60;89;59;64;61;61;92;67 10:53:21 INFO - PID 2505 | |4;audio-oscillator;83;70;86;72;58;83;107;104;86;85 10:53:21 INFO - PID 2505 | |5;imaging-gaussian-blur;91;92;91;91;98;91;91;91;109;87 10:53:21 INFO - PID 2505 | |6;imaging-darkroom;97;99;98;98;99;97;99;124;98;99 10:53:21 INFO - PID 2505 | |7;imaging-desaturate;99;124;91;94;91;92;92;93;112;110 10:53:21 INFO - PID 2505 | |8;json-parse-financial;52;51;50;51;51;51;51;51;50;51 10:53:21 INFO - PID 2505 | |9;json-stringify-tinderbox;61;58;61;56;64;60;58;57;58;57 10:53:21 INFO - PID 2505 | |10;stanford-crypto-aes;105;87;76;101;108;120;80;113;134;103 10:53:21 INFO - PID 2505 | |11;stanford-crypto-ccm;139;144;169;127;143;154;153;140;157;151 10:53:21 INFO - PID 2505 | |12;stanford-crypto-pbkdf2;190;191;187;187;179;186;186;181;239;237 10:53:21 INFO - PID 2505 | |13;stanford-crypto-sha256-iterative;60;91;97;95;98;65;64;67;71;73 10:53:21 INFO - PID 2505 | __end_tp_report 10:53:21 INFO - PID 2505 | __start_cc_report 10:53:21 INFO - PID 2505 | _x_x_mozilla_cycle_collect,1208 10:53:21 INFO - PID 2505 | __end_cc_report 10:53:21 INFO - PID 2505 | __startTimestamp1496166801892__endTimestamp 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | ------- Summary: start ------- 10:53:21 INFO - PID 2505 | Number of tests: 14 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#0] ai-astar Cycles:10 Average:126.00 Median:141.00 stddev:24.62 (17.5%) stddev-sans-first:24.91 10:53:21 INFO - PID 2505 | Values: 105.0 116.0 115.0 114.0 110.0 170.0 151.0 131.0 154.0 94.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:141.00 stddev:27.09 (19.2%) stddev-sans-first:28.57 10:53:21 INFO - PID 2505 | Values: 138.0 112.0 122.0 184.0 97.0 112.0 144.0 100.0 157.0 131.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#2] audio-dft Cycles:10 Average:217.90 Median:215.50 stddev:23.26 (10.8%) stddev-sans-first:23.95 10:53:21 INFO - PID 2505 | Values: 202.0 211.0 220.0 226.0 244.0 270.0 204.0 202.0 197.0 203.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#3] audio-fft Cycles:10 Average:69.80 Median:65.50 stddev:13.54 (20.7%) stddev-sans-first:13.03 10:53:21 INFO - PID 2505 | Values: 86.0 59.0 60.0 89.0 59.0 64.0 61.0 61.0 92.0 67.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:83.00 stddev:14.73 (17.7%) stddev-sans-first:15.62 10:53:21 INFO - PID 2505 | Values: 83.0 70.0 86.0 72.0 58.0 83.0 107.0 104.0 86.0 85.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:91.00 stddev:6.16 (6.8%) stddev-sans-first:6.48 10:53:21 INFO - PID 2505 | Values: 91.0 92.0 91.0 91.0 98.0 91.0 91.0 91.0 109.0 87.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:98.50 stddev:8.19 (8.3%) stddev-sans-first:8.57 10:53:21 INFO - PID 2505 | Values: 97.0 99.0 98.0 98.0 99.0 97.0 99.0 124.0 98.0 99.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#7] imaging-desaturate Cycles:10 Average:99.80 Median:92.00 stddev:11.53 (12.5%) stddev-sans-first:12.22 10:53:21 INFO - PID 2505 | Values: 99.0 124.0 91.0 94.0 91.0 92.0 92.0 93.0 112.0 110.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:0.57 (1.1%) stddev-sans-first:0.44 10:53:21 INFO - PID 2505 | Values: 52.0 51.0 50.0 51.0 51.0 51.0 51.0 51.0 50.0 51.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:2.45 (4.2%) stddev-sans-first:2.49 10:53:21 INFO - PID 2505 | Values: 61.0 58.0 61.0 56.0 64.0 60.0 58.0 57.0 58.0 57.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#10] stanford-crypto-aes Cycles:10 Average:102.70 Median:127.00 stddev:17.94 (14.1%) stddev-sans-first:19.01 10:53:21 INFO - PID 2505 | Values: 105.0 87.0 76.0 101.0 108.0 120.0 80.0 113.0 134.0 103.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:152.00 stddev:11.63 (7.7%) stddev-sans-first:11.91 10:53:21 INFO - PID 2505 | Values: 139.0 144.0 169.0 127.0 143.0 154.0 153.0 140.0 157.0 151.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.30 Median:188.50 stddev:22.28 (11.8%) stddev-sans-first:23.51 10:53:21 INFO - PID 2505 | Values: 190.0 191.0 187.0 187.0 179.0 186.0 186.0 181.0 239.0 237.0 10:53:21 INFO - PID 2505 | 10:53:21 INFO - PID 2505 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:82.00 stddev:15.29 (18.6%) stddev-sans-first:14.74 10:53:21 INFO - PID 2505 | Values: 60.0 91.0 97.0 95.0 98.0 65.0 64.0 67.0 71.0 73.0 10:53:21 INFO - PID 2505 | -------- Summary: end -------- 10:53:21 INFO - PID 2505 | 10:53:22 INFO - TEST-INFO | 2505: exit 0 10:53:22 INFO - TEST-OK | kraken | took 91334ms 10:53:22 INFO - SUITE-END | took 1235s 10:53:23 INFO - Completed test suite (00:20:35) 10:53:23 INFO - javascript benchmark 10:53:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7957.3814542865075, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3562.1272788166903, 3577.284543091381, 3611.693006094482, 3613.927721445571, 3624.275144971005, 6146.524834039832, 6173.043178307364, 6211.440970312677, 6228.252609686839, 6292.8741425171465, 5149.922751158726, 6164.383561643834, 6199.628022318653, 6277.497800175986, 6292.937070629304, 5253.816116435925, 5441.673499590018, 5474.315710536183, 5488.725563721818, 5519.9448005519835, 4968.0, 5527.060399732055, 5605.355384130816, 5630.605857589971, 5635.408282130379, 4688.5545873142, 5222.294990176316, 5406.783728650849, 5436.401995780461, 5485.50630443262, 4387.495438024636, 4881.731504767236, 5509.0, 5514.5864060195245, 5515.696636684981, 5401.945980540223, 5508.228847961289, 5526.778928842841, 5530.944690553083, 5576.0, 6123.343628574248, 6132.172156758825, 6188.690565471708, 6194.101855230966, 6271.749130034793, 6802.319768023208, 6817.250102488722, 6826.590404575742, 6829.043933849265, 6841.110655614738, 6915.792526224222, 6916.1700595928305, 6924.23833378328, 6927.618980956046, 6929.89605155918, 6227.906581401283, 6303.086052522359, 6316.0, 6334.458196463915, 6393.840153996094, 7005.579665220103, 7033.226345102034, 7052.9647351762915, 7056.576605403692, 7057.89413158803, 6390.936090639034, 6507.446867016262, 6523.934760652428, 6597.274299827015, 6645.568038077607, 6793.45652347811, 6856.6228857412325, 6862.656867156622, 6868.931310686829, 6888.138982627172, 6506.577072490273, 6696.799096027127, 6728.192616886005, 6741.797746067626, 6748.055272261888, 6542.738290468423, 6647.135872336566, 6698.933010669928, 6730.629815360202, 6777.152855893013, 5387.973060134753, 5394.595405344615, 5400.108982018001, 5415.83752487426, 5423.620346575702, 4800.759962001886, 4806.639502037291, 4828.203346447796, 4837.540433658867, 4849.854504364875, 6220.346863579259, 6252.468540174136, 6292.402221788922, 6321.715522801393, 6351.872962540723, 6863.38229559342, 6883.793486195422, 6890.0, 6893.138357705287, 6913.89629155553, 6267.247930248308, 6355.650439225887, 6359.300476947532, 6378.936210637927, 6403.359664033559, 5788.479036886616, 5830.096335068071, 5898.852528686817, 5947.881042379128, 6118.6022908511695, 6970.546914450544, 7014.824629384204, 7027.578345299298, 7038.120234970629, 7045.2954704531185, 6856.828579285558, 6872.931270687229, 6873.48448866327, 6879.277675843964, 6896.586204827727, 6789.422899053636, 6874.312568743086, 6962.268961758942, 7041.3310735480045, 7042.330978557029, 6597.901031484435, 6717.1267735194115, 6751.459883209233, 6782.626955517297, 6789.72841086351, 6799.3200679931615, 6870.45036397097, 6879.105716256855, 6892.345227203407, 6935.167779866449, 6398.5201109916015, 6432.871342573216, 6519.934800652122, 6582.17722784652, 6635.966820165868, 6619.437347825488, 6646.069550263063, 6652.800415987334, 6664.933350666432, 6669.6331701754925, 6385.521085918482, 6449.258335291313, 6477.870442591027, 6479.0, 6506.772262970773, 6408.61548307103, 6465.77369792055, 6562.50781191403, 6648.301928297459, 6674.299198584273, 5574.247476590608, 6325.778597749057, 6370.8088757337355, 6430.067640192239, 6456.225252969673], "value": 6164.317418750328, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.4551422319475, 764.7514557768726, 807.8384323135373, 1158.4265788434725, 1245.053759143051, 29637.703622963792, 39877.80061099694, 39943.600563994354, 40021.0, 40146.0, 39852.80073599635, 40065.59934400655, 40160.99999999993, 40236.0, 40484.78545643628, 7986.281234688877, 8184.713535026275, 8458.323334133254, 8656.48061116335, 8657.870131948026, 8467.110953349902, 8548.273396761282, 8569.700060497884, 8585.01272353678, 8608.52653104079, 8536.0, 8543.316534677211, 8560.48637081774, 8576.528290943996, 8586.31309495242, 8515.191056849651, 8524.445911015764, 8526.872096918582, 8529.104444033412, 8570.528620925847, 8329.208725171098, 8351.874721879176, 8386.8322633547, 8392.412531122824, 8394.958025209897, 37257.44113838294, 37488.43767343492, 37552.24895502102, 37680.6811761587, 37713.81143094267, 3597.6402359764074, 3639.7452178347526, 3647.270545890806, 3647.5622925248877, 3652.5616925969052, 30479.39041219163, 30669.8466507666, 30791.538126928113, 30825.921092761644, 30858.074257772303, 3093.8143711377165, 3128.6871312868757, 3131.264152924061, 3140.952885706716, 3151.11768704763, 241550.0, 242304.0, 283064.99999999796, 301258.49370753224, 301576.0, 48218.75890620524, 48356.27465588019, 48431.78920527015, 48501.5149848497, 48643.5135648646, 50313.74843125761, 50406.0, 50415.747921260896, 50513.74743126261, 50590.98818023619, 13103.410346534429, 13296.867031329562, 13307.135036222615, 13311.66720831987, 13360.06479546442, 10899.727506812393, 11007.119430445546, 11012.559497620166, 11033.951774581563, 11049.613263535737, 13952.651183720489, 13968.580942571536, 13988.300584970711, 14065.226412547203, 14126.223057731719, 6093.329733729286, 6097.420745029215, 6146.108814222002, 6148.800983808204, 6171.352008039091, 885.291766586728, 936.513013233115, 1027.106417416852, 1046.7487802927242, 1054.5570860238718, 20890.0, 23564.64653030206, 24424.0, 24571.186313873022, 24604.876975615007, 1081.7836432713332, 1092.4974511724695, 1098.9395583242838, 1122.7866705325962, 1128.1877048525048, 11986.400679965967, 12615.0, 12683.619491415087, 12709.555165569162, 13002.674933126747, 289639.55180224386, 290174.0, 290605.0, 290921.0, 291395.0, 53416.198757018676, 53566.464335356926, 53738.46261537413, 53778.46221537813, 53839.46160538345, 1486.3831509923477, 1495.2150121186464, 1527.9312430940633, 1529.2353823088456, 1530.3419529602377, 23272.65091023602, 24093.879530602233, 24333.0, 24361.512769744153, 24405.877970610745, 37830.62169378271, 37896.81051594725, 37929.62070379261, 45751.77124114358, 50030.24954625756, 280027.0, 281708.5914570414, 282347.17701818293, 282439.0, 284611.0, 34452.65547344595, 35996.82001589975, 36043.81978090198, 36120.916372508866, 36129.91610251697, 44365.77817111023, 44654.553454465036, 45864.77067614641, 45960.31059534043, 46264.53735462602, 29942.550861737527, 30742.077737667907, 31317.373652526367, 31448.056558303288, 32056.83971580127, 27999.0200342987, 30450.086497405115, 30737.538936915516, 31016.0695179145, 31405.528917065807, 36848.81575592105, 37790.811045944596, 37879.62120378761, 37911.0, 37963.43054854124, 29176.27059323534, 30710.692893070784, 30867.845660771553, 31509.8424507876, 31735.84132079325, 23759.812009399808, 24078.0, 24114.397140070938, 24185.63721544143, 24403.26790196297], "value": 17565.74569841869, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.77452549394985, 426.1817310763334, 432.21984318305454, 442.92248856450135, 456.96801223914326, 19053.904730476363, 20920.37238882832, 21679.024443900023, 21829.345119646438, 21952.0, 22239.22162724305, 23124.537509249803, 23345.649815252786, 23413.297601071994, 23414.946327415266, 5887.411258874111, 6192.380761923817, 6198.78304259351, 6199.349068347826, 6199.876002479948, 5909.940900590982, 5982.042873140298, 6029.366916473773, 6050.546209034318, 6139.109829074781, 5943.108533719934, 5982.551308651847, 5989.850253743648, 6002.709417475242, 6013.368596297391, 5687.601867869251, 5965.0, 5973.970130149343, 5992.43071908168, 5993.580449368546, 5351.25082488452, 5360.463953604647, 5361.544268737162, 5363.490468405535, 5382.434844341366, 27527.724722752915, 28184.436311273865, 28210.012649557168, 28246.858765706245, 28257.0, 2106.2101711858054, 2159.6976423300753, 2164.4805246740825, 2167.100653228908, 2170.6526955687013, 16124.919375403048, 17831.821681783276, 18164.45506634803, 18421.447356579323, 18430.723539146922, 2115.122224276923, 2140.550484398278, 2171.6742488626674, 2173.3154056472317, 2174.1955476473804, 140987.0, 157106.0, 162886.0, 164609.0, 175196.0, 11734.295942243492, 12564.371781411075, 12932.03009774252, 12969.76787205214, 13001.219926804233, 16414.835851641332, 16492.010479371038, 16513.83486165123, 16524.83475165257, 16541.338346466007, 8572.914270857336, 8753.299736021103, 8769.956150219208, 8826.205641492168, 8836.425632333878, 8159.67361305553, 8258.752237432887, 8279.62741676614, 8391.286740626992, 8410.803894520166, 7792.844143117106, 7899.131095579482, 7937.682492700343, 7968.645330293864, 7989.640466179036, 2106.4733816545863, 2112.7570329412006, 2119.4807272218404, 2124.7662757096705, 2284.7372552156717, 898.7797989492485, 969.4958621516913, 991.8908920018937, 994.7171241999483, 1014.5434554450527, 20532.486687832625, 20914.0, 21490.57018859614, 21516.569668606855, 21579.568408632054, 994.4381424495137, 1004.6785028790863, 1006.9798604028025, 1014.4826138669332, 1044.8506642693128, 8997.730068097968, 10653.094486968539, 10941.890581094247, 10982.231243812856, 11016.724581885357, 508469.3729594059, 571091.0, 577580.0, 581974.0, 582158.0, 99622.0, 100757.49621251994, 117516.0, 136336.0, 137126.31436842753, 1067.1889364083197, 1163.8836116388463, 1169.9941500292443, 1214.107630891294, 1252.9812052819216, 21858.562828743656, 22892.4276893079, 23036.654450182927, 23076.769232307463, 23222.767772322062, 56265.71867140638, 58329.0, 58444.415555843894, 59052.704736476044, 59343.40656593552, 78328.60835695785, 78728.60635696785, 78943.60528197323, 79248.60375698084, 79770.0, 8860.335474839541, 8991.190792828676, 9011.459312441275, 9099.54502274897, 9128.0, 11851.466683999068, 13783.37974791157, 13928.164310141425, 14244.287785610884, 14293.927955403595, 8609.311255099703, 9241.260699144186, 11743.354115523729, 11811.822822657496, 11966.760664786481, 11158.776824463628, 11373.0, 11400.144989125683, 11522.654320370402, 11631.011364034151, 11600.88399115998, 11743.060555155738, 11828.526858925547, 12015.798420157915, 12174.939125304316, 8643.222110010129, 8747.562621869009, 9016.594253258489, 9056.094390560891, 9109.407888487493, 11326.37704926237, 11779.469923853276, 12058.336791476553, 12116.394180290774, 12147.817782733442], "value": 10451.925180157898, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1215.2769102384082, 1452.5569701241118, 1473.3075454536365, 1481.9996502360905, 1487.8512148785119, 11381.430928453576, 11611.013063889579, 11771.646850594496, 11795.882041179586, 11983.880161198385, 11641.476133573988, 11716.589919352804, 11816.645500634973, 11830.29018258903, 11849.703757406069, 2511.2591785423297, 2539.149384956042, 2552.629868669042, 2553.195743340851, 2560.886014583662, 2481.305234534333, 2512.0705339024494, 2517.496500699858, 2517.8992840286364, 2520.407704189514, 2456.115798312606, 2474.134053081416, 2475.9876200618974, 2486.2168416948693, 2487.502499500098, 2377.441301294203, 2408.434018005767, 2409.9277021689377, 2410.0, 2418.5404773093055, 2400.7479214682558, 2406.049610403898, 2406.759324067597, 2412.8069754419603, 2503.2240005598323, 11343.829842552368, 11438.656840294805, 11444.94277528607, 11449.942750286278, 11479.426028698532, 6206.379362063757, 6433.420992110732, 6466.385693359123, 6474.384933431316, 6484.902726459107, 3785.3754130568423, 3994.0613955720673, 4163.500379954395, 4249.5113061997645, 4249.532551419341, 1247.5072346423112, 1247.8689737577515, 1262.072376803049, 1262.873712628739, 1279.32195936154, 14970.17664028478, 15461.072335659896, 16022.19889005545, 16099.91950040254, 16101.43644972432, 5387.353517577837, 5462.890742185134, 5531.585131115183, 5585.218069470277, 5594.524465420485, 11625.825612615818, 11702.180847340604, 11710.648680539598, 11712.824307635392, 11743.823842642367, 6195.380461953769, 6331.9366806331345, 6391.744330226739, 6404.423601875854, 6422.229332480132, 5651.717414129277, 5680.715964201773, 6087.817365478954, 6102.7558897644485, 6131.202943617302, 4328.285832837547, 4404.66964977628, 4486.394336764561, 4502.414686090788, 4527.547245275512, 1244.4710997825887, 1252.8308678328308, 1264.1909178126007, 1267.3789842976876, 1275.9808602870964, 671.8253254153955, 809.465752603279, 813.2761841960541, 813.7029984055777, 824.2911096457042, 5502.174673798882, 6186.536009799283, 6248.4064013918605, 6345.302016778151, 6354.3645635437015, 528.0231571592523, 563.4027930393796, 590.7814108779779, 601.4316470935012, 602.0968547179231, 3804.0, 4046.7167298288837, 4123.309637874086, 4274.722143060691, 4328.978355108205, 14236.572902812932, 14633.195174265518, 14974.625634359229, 14985.775213371808, 14988.0, 11896.464659090361, 14261.500847470288, 14728.484503042342, 14868.959172858013, 14895.0, 8378.539180345018, 8422.6209820557, 8559.486430814171, 8582.369349823639, 8603.698870539502, 7007.369336759716, 7158.534695244793, 7302.415806735555, 7338.889916651361, 7348.522346047704, 13381.866181338062, 13468.93265533666, 13510.662233444242, 13525.256110913993, 13638.863611364157, 13870.930645346709, 13955.0, 14029.298535073409, 14042.297885105909, 14242.928785356007, 9300.860802015439, 9452.763680908032, 9465.05349465048, 9467.573959171992, 9505.572249248939, 10516.631917882838, 10620.893791062299, 10756.139508839431, 10799.730006749895, 11030.393328366696, 8928.241099506615, 9166.129217724305, 9226.35415520907, 9227.261819054595, 9282.72151835446, 8875.73372798817, 8980.86528702057, 9037.68368107113, 9057.637694492147, 9138.360314777901, 8169.0605580359015, 8314.0, 8327.250547450758, 8341.791455213668, 8345.707900223462, 5007.874803129951, 5641.887162256649, 5710.114932185518, 5752.827415177551, 5828.184054232326, 6086.69566521681, 6203.31763506005, 6205.968970155301, 6224.408681175281, 6285.151504546828], "value": 5272.886168215147, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.6498841205147, 386.8278616015873, 392.6270043458715, 394.5245978595792, 405.8680928698172, 5907.911381329278, 6283.183186185795, 6286.528510361718, 6301.810945671626, 6349.80950571483, 5336.759845806938, 5977.252843394575, 6125.387461253884, 6167.660778657172, 6193.597416167946, 4065.3901914712883, 4667.346571479987, 4683.69555978862, 4696.43642762869, 4731.7634118293945, 3873.2447172801317, 4662.813487460515, 4683.789229484664, 4690.882727931795, 4743.952560474386, 4505.662075344346, 4525.230710779176, 4655.185342565054, 4655.278431843053, 4660.464046634646, 3902.9609703903025, 4638.976805115986, 4641.955559999007, 4652.790624421909, 4734.431868175807, 3573.428251479777, 4090.734102283342, 4094.8566800161852, 4101.118259574206, 4115.156392939471, 5930.458080898998, 5985.0, 6003.219581454427, 6016.548758843104, 6029.547783916224, 4945.802167913279, 5555.333359996826, 5563.0, 5585.374873809303, 5591.860203494904, 5102.0, 6357.523185761086, 6604.966975165094, 6691.19705635322, 6763.898541521881, 3513.1568423578155, 4386.056997745468, 4386.947132688132, 4408.294672852328, 4409.757463339515, 5640.251522028185, 6569.967150164219, 6895.138107736583, 7140.3573678368675, 7192.784216473514, 4028.879133625996, 4929.679570827885, 5023.6985780853265, 5053.54518093366, 5087.4403815581, 4111.342185250345, 4672.32251323563, 4982.829035176731, 5031.371078615173, 5070.188769796889, 5291.973540132275, 5612.242347283064, 5661.207430959669, 5671.886562268732, 5737.3688894221605, 6190.288116866528, 6376.29860715321, 6400.487960963112, 6749.0, 6768.661566921634, 1567.529741077672, 1690.3661127077346, 1749.9387521436688, 1770.8224031019229, 1776.253973331204, 1210.0863847794858, 1217.8843009914044, 1244.4337826289018, 1250.2186133666457, 1259.9370031498388, 5275.314209152785, 5734.111212762028, 6231.626102433868, 6274.372562743689, 6366.395192456709, 6882.8967565486555, 6961.79114626562, 6968.3728464438445, 6987.685554150075, 7008.84354081581, 5638.323401191801, 6031.884101441247, 6142.293636231801, 6180.227471566054, 6272.020790124654, 3046.9238269043453, 3261.972478669212, 3630.2920930418845, 3630.8910732678064, 3636.9636303637153, 6535.901961470582, 6961.825954351182, 7027.754028608974, 7110.8978108393285, 7214.783556493314, 7120.572765634079, 7122.358987691133, 7171.247019063027, 7206.279372062857, 7273.290776667584, 6347.857385670623, 6730.730770769114, 6947.786962435906, 7148.749793757298, 7169.88488395914, 5889.646621202742, 6900.964855271749, 6953.79138625842, 6967.477439191979, 6994.930050699428, 5783.8554036149435, 6098.786542470993, 6204.81385558434, 6319.96840015797, 6872.62200578973, 5841.678707671119, 7025.473089518205, 7177.210506844347, 7235.312645298688, 7241.601711905896, 4239.406483092308, 4440.134173836071, 4484.946037681077, 4492.932606010978, 4499.932501012422, 6648.301928297459, 6860.176778786378, 7025.243689077731, 7071.418932089853, 7156.892646610409, 6018.699065046642, 6356.745730170926, 6509.81567510876, 6751.696173672099, 6757.391834734701, 6598.703058362481, 6651.503411732515, 6692.0, 6747.6626168692355, 6782.728690852311, 5398.78404863801, 6286.214223222098, 6602.933970660232, 6620.040094186413, 6653.600783952978, 6048.576599637983, 6596.70314835824, 6766.391024807791, 6791.7095751807, 6877.312268773083, 5293.867653308698, 5501.312335958005, 6104.542159338156, 6504.544681872224, 6529.934700652932], "value": 4905.1760128852675, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.6684558525872, 778.5250996891898, 820.7742870710557, 835.5070508400047, 868.1882439918675, 14446.855531444695, 16736.41422550211, 17439.302427902883, 19315.517112072204, 19958.0, 18208.0, 18749.812501874978, 18865.056747162656, 18959.715604265948, 19028.524286892836, 983.4443539400233, 995.149147478906, 997.7655251016042, 997.7755005123826, 1003.3027046202856, 987.2151639446621, 994.2195376629347, 994.5773046455228, 994.9651762188325, 995.3082607587729, 1054.96307629233, 1068.4070340960764, 1068.9732756681067, 1069.4652673663168, 1070.2133931560336, 1045.9529321180564, 1053.789242151569, 1066.0086119908444, 1066.296244478648, 1066.5946940162728, 682.1439093937126, 758.416019664855, 761.6708843068881, 768.1511929318135, 781.0666253826679, 17273.395431159974, 17464.65070698579, 17726.645467090588, 18079.276828926828, 18259.087045647666, 17581.56046098845, 17648.294068237254, 17706.645867082585, 17990.1004949752, 18010.549736256566, 14561.563153105424, 14793.778093328609, 14943.476978305707, 14954.62613434662, 15001.924990375088, 3579.391503444421, 3640.0, 3641.6904563111902, 3649.5802982656805, 3650.2152037312107, 128354.0, 155401.89196216126, 160101.0, 162844.0, 163318.0, 13567.0, 13705.177689338672, 13823.447062117604, 13860.861391386159, 13879.167249965034, 22342.0, 25488.490230195293, 26491.735082649313, 26883.193504194904, 27216.319592010357, 7890.8816367754525, 7919.920800791919, 7967.28294453502, 7979.72070977513, 7991.160928102582, 3892.649661530476, 3972.5828787977425, 4005.0187704012706, 4016.6987475938836, 4027.368915589191, 4238.3006803877015, 4298.570142985676, 4332.805023773938, 4338.6312163466455, 4341.479022517255, 779.2402407652494, 820.6553247635887, 823.9423240373117, 862.3446180902433, 864.8356812205661, 638.4317957018258, 656.5929123943185, 664.4385494257344, 672.6334147889412, 681.9147606549182, 11006.064484518745, 11152.832707509393, 11591.24656897299, 11675.941620291844, 12144.392780360946, 619.3837132053635, 644.5649186798986, 648.5622205011598, 670.6412069542771, 673.7338751193251, 5385.138377859602, 5623.297087864017, 5801.042827933428, 6341.746330146835, 6395.52033597482, 132759.67240327667, 142368.5763142376, 145225.0, 146193.5380646201, 147500.26249868682, 100720.0, 102232.48883755534, 103506.0, 113706.0, 115721.0, 77558.6122069386, 82714.17285827185, 101857.49071254744, 104273.47863260788, 104978.47510762398, 10929.0, 11724.472398741906, 12215.572454964034, 12654.987600992083, 12712.491500340253, 42886.35670464883, 42945.785271073444, 43409.56590434181, 43543.78228108966, 44710.77644611756, 59170.11244831245, 59399.70300148472, 59511.0, 59715.40284597098, 60189.69905150446, 14893.925530372278, 16442.588935276715, 17069.57326066858, 17283.271672833118, 17287.82712172862, 13001.0, 15452.227388630748, 15591.220438978233, 15709.921450393133, 15754.842451575338, 17176.82823171752, 17341.479755607354, 17601.735973960145, 17989.460316190536, 18069.909650451664, 14381.496647617283, 15121.017133886262, 16721.91639041797, 17097.0, 17614.943103413836, 16244.350225990827, 16555.66888662244, 16928.66142677115, 17116.972981621144, 17241.91379043097, 11878.0, 12088.0, 12179.269243845398, 12300.200486968677, 12342.382880855741, 12139.150259481752, 13051.543195988095, 13058.608241752383, 13160.539381121614, 13332.666683332995], "value": 8672.413178881205, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 114.0, 110.0, 170.0, 151.0, 131.0, 154.0, 94.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 112.0, 122.0, 184.0, 97.0, 112.0, 144.0, 100.0, 157.0, 131.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 211.0, 220.0, 226.0, 244.0, 270.0, 204.0, 202.0, 197.0, 203.0], "value": 217.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 59.0, 60.0, 89.0, 59.0, 64.0, 61.0, 61.0, 92.0, 67.0], "value": 69.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 86.0, 72.0, 58.0, 83.0, 107.0, 104.0, 86.0, 85.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 91.0, 98.0, 91.0, 91.0, 91.0, 109.0, 87.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 98.0, 98.0, 99.0, 97.0, 99.0, 124.0, 98.0, 99.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 124.0, 91.0, 94.0, 91.0, 92.0, 92.0, 93.0, 112.0, 110.0], "value": 99.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 51.0, 51.0, 51.0, 51.0, 51.0, 50.0, 51.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 61.0, 56.0, 64.0, 60.0, 58.0, 57.0, 58.0, 57.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 87.0, 76.0, 101.0, 108.0, 120.0, 80.0, 113.0, 134.0, 103.0], "value": 102.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 144.0, 169.0, 127.0, 143.0, 154.0, 153.0, 140.0, 157.0, 151.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 191.0, 187.0, 187.0, 179.0, 186.0, 186.0, 181.0, 239.0, 237.0], "value": 196.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 91.0, 97.0, 95.0, 98.0, 65.0, 64.0, 67.0, 71.0, 73.0], "value": 78.1, "unit": "score"}]}]} 10:53:23 INFO - Return code: 0 10:53:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:53:23 INFO - # TBPL SUCCESS # 10:53:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:53:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:53:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:53:23 INFO - 2017-05-30 10:53:23.196 screenresolution[2513:38739] starting screenresolution argv=screenresolution get 10:53:23 INFO - 2017-05-30 10:53:23.198 screenresolution[2513:38739] Display 0: 1600x1200x32@60 10:53:23 INFO - 2017-05-30 10:53:23.204 screenresolution[2514:38741] starting screenresolution argv=screenresolution list 10:53:23 INFO - Available Modes on Display 0 10:53:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:53:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:53:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:53:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:53:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:53:23 INFO - Intel Iris: 10:53:23 INFO - Chipset Model: Intel Iris 10:53:23 INFO - Type: GPU 10:53:23 INFO - Bus: Built-In 10:53:23 INFO - VRAM (Dynamic, Max): 1536 MB 10:53:23 INFO - Vendor: Intel (0x8086) 10:53:23 INFO - Device ID: 0x0a2e 10:53:23 INFO - Revision ID: 0x0009 10:53:23 INFO - Displays: 10:53:23 INFO - Display: 10:53:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:53:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:53:23 INFO - Display Serial Number: 1600x1200 60 10:53:23 INFO - Main Display: Yes 10:53:23 INFO - Mirror: Off 10:53:23 INFO - Online: Yes 10:53:23 INFO - Rotation: Supported 10:53:23 INFO - Return code: 0 10:53:23 INFO - Running post-action listener: _resource_record_post_action 10:53:23 INFO - [mozharness: 2017-05-30 17:53:23.440795Z] Finished run-tests step (success) 10:53:23 INFO - Running post-run listener: _resource_record_post_run 10:53:23 INFO - Total resource usage - Wall time: 1258s; CPU: 54.0%; Read bytes: 1929728; Write bytes: 569979392; Read time: 16; Write time: 2072 10:53:23 INFO - TinderboxPrint: CPU usage
53.6% 10:53:23 INFO - TinderboxPrint: I/O read bytes / time
1,929,728 / 16 10:53:23 INFO - TinderboxPrint: I/O write bytes / time
569,979,392 / 2,072 10:53:23 INFO - TinderboxPrint: CPU idle
2,336.1 (46.4%) 10:53:23 INFO - TinderboxPrint: CPU system
1,029.1 (20.4%) 10:53:23 INFO - TinderboxPrint: CPU user
1,668.5 (33.1%) 10:53:23 INFO - TinderboxPrint: Swap in / out
247,656,448 / 0 10:53:23 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226062848; Write bytes: 220637184; Read time: 16608; Write time: 495 10:53:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:53:23 INFO - run-tests - Wall time: 1237s; CPU: 54.0%; Read bytes: 1929728; Write bytes: 321014272; Read time: 16; Write time: 1509 10:53:23 INFO - Running post-run listener: _upload_blobber_files 10:53:23 INFO - Blob upload gear active. 10:53:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:53:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:53:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:53:24 INFO - (blobuploader) - INFO - Open directory for files ... 10:53:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:53:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:53:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:53:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:53:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:53:25 INFO - (blobuploader) - INFO - Done attempting. 10:53:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:53:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:53:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:53:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:53:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:53:26 INFO - (blobuploader) - INFO - Done attempting. 10:53:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:53:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:53:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:53:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:53:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:53:28 INFO - (blobuploader) - INFO - Done attempting. 10:53:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:53:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:53:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:53:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:53:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:53:30 INFO - (blobuploader) - INFO - Done attempting. 10:53:30 INFO - (blobuploader) - INFO - Iteration through files over. 10:53:30 INFO - Return code: 0 10:53:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:53:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:53:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd527d0e98cc6ef142c3b4164ddcedee199a7548d42d847f442863b8c9ca902098ddd37cb6efc9a80eba9e8936de1bf9059a208ecfe18a4f7ae443f90144692f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc1965784c95e7f8bf82e29922b12d1b2442d9a4b37989be1e2c2d32b232fb17768a7f7e8b1ed39858310bf7e5b4808728320a42e17051c41f48c223f79b4c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/293beafd4a8a5550f80af51d5fd30485eb8ce187533fd925fce611fd82549a345352fe5c81885d156b3b442f56def5a721e7eee7e28532e772c0c960085cf840", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1219dd288f455d233b0f70815553f9c81ed4d4e5ebb24d74994f9d34d933efa3d3180dad88976bb3e36674caec044f34d3d67889321e86cdacf99e9c4ce4c1e"} 10:53:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:53:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:53:30 INFO - Contents: 10:53:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd527d0e98cc6ef142c3b4164ddcedee199a7548d42d847f442863b8c9ca902098ddd37cb6efc9a80eba9e8936de1bf9059a208ecfe18a4f7ae443f90144692f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc1965784c95e7f8bf82e29922b12d1b2442d9a4b37989be1e2c2d32b232fb17768a7f7e8b1ed39858310bf7e5b4808728320a42e17051c41f48c223f79b4c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/293beafd4a8a5550f80af51d5fd30485eb8ce187533fd925fce611fd82549a345352fe5c81885d156b3b442f56def5a721e7eee7e28532e772c0c960085cf840", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1219dd288f455d233b0f70815553f9c81ed4d4e5ebb24d74994f9d34d933efa3d3180dad88976bb3e36674caec044f34d3d67889321e86cdacf99e9c4ce4c1e"} 10:53:30 INFO - Running post-run listener: copy_logs_to_upload_dir 10:53:30 INFO - Copying logs to upload dir... 10:53:30 INFO - mkdir: /builds/slave/test/build/upload/logs 10:53:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.925138 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-30 10:53:30.797946) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 10:53:30.801205) ========= 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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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/cd527d0e98cc6ef142c3b4164ddcedee199a7548d42d847f442863b8c9ca902098ddd37cb6efc9a80eba9e8936de1bf9059a208ecfe18a4f7ae443f90144692f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc1965784c95e7f8bf82e29922b12d1b2442d9a4b37989be1e2c2d32b232fb17768a7f7e8b1ed39858310bf7e5b4808728320a42e17051c41f48c223f79b4c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/293beafd4a8a5550f80af51d5fd30485eb8ce187533fd925fce611fd82549a345352fe5c81885d156b3b442f56def5a721e7eee7e28532e772c0c960085cf840", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1219dd288f455d233b0f70815553f9c81ed4d4e5ebb24d74994f9d34d933efa3d3180dad88976bb3e36674caec044f34d3d67889321e86cdacf99e9c4ce4c1e"} build_url:https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/XUqH7yAUT5qUCZvyGoPsgA/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/cd527d0e98cc6ef142c3b4164ddcedee199a7548d42d847f442863b8c9ca902098ddd37cb6efc9a80eba9e8936de1bf9059a208ecfe18a4f7ae443f90144692f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc1965784c95e7f8bf82e29922b12d1b2442d9a4b37989be1e2c2d32b232fb17768a7f7e8b1ed39858310bf7e5b4808728320a42e17051c41f48c223f79b4c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/293beafd4a8a5550f80af51d5fd30485eb8ce187533fd925fce611fd82549a345352fe5c81885d156b3b442f56def5a721e7eee7e28532e772c0c960085cf840", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1219dd288f455d233b0f70815553f9c81ed4d4e5ebb24d74994f9d34d933efa3d3180dad88976bb3e36674caec044f34d3d67889321e86cdacf99e9c4ce4c1e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 10:53:30.837102) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:53:30.837498) ========= 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.XHmYWnefa8/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.cOw3TcFC8Y/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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.005173 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:53:30.886953) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 10:53:30.887659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 10:53:30.888079) ========= ========= Total master_lag: 0.65 =========