builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0259 starttime: 1495779241.29 results: success (0) revision: 5d6fe59a9a5dfd2d381d2cdfdc968d3305b417cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:01.286348) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:01.286822) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:01.287204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:01.314241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:01.314847) ========= 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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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-25 23:14:01-- 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% 50.3M=0s 2017-05-25 23:14:01 (50.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153605 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:01.494040) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:01.494561) ========= 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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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.027925 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:01.545744) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 23:14:01.546118) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5d6fe59a9a5dfd2d381d2cdfdc968d3305b417cf --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 5d6fe59a9a5dfd2d381d2cdfdc968d3305b417cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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-25 23:14:01,685 truncating revision to first 12 chars 2017-05-25 23:14:01,685 Setting DEBUG logging. 2017-05-25 23:14:01,685 attempt 1/10 2017-05-25 23:14:01,685 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5d6fe59a9a5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 23:14:03,042 unpacking tar archive at: mozilla-central-5d6fe59a9a5d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.641883 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 23:14:03.209597) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:03.212953) ========= script_repo_revision: 5d6fe59a9a5dfd2d381d2cdfdc968d3305b417cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:03.213321) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:03.213609) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 23:14:03.232612) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-25 23:14:03.233000) ========= /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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:14:03 INFO - MultiFileLogger online at 20170525 23:14:03 in /builds/slave/test 23:14:03 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 23:14:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:14:03 INFO - {'append_to_log': False, 23:14:03 INFO - 'base_work_dir': '/builds/slave/test', 23:14:03 INFO - 'blob_upload_branch': 'Firefox', 23:14:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:14:03 INFO - 'branch': 'Firefox', 23:14:03 INFO - 'buildbot_json_path': 'buildprops.json', 23:14:03 INFO - 'config_files': ('talos/mac_config.py',), 23:14:03 INFO - 'default_actions': ('clobber', 23:14:03 INFO - 'read-buildbot-config', 23:14:03 INFO - 'download-and-extract', 23:14:03 INFO - 'populate-webroot', 23:14:03 INFO - 'create-virtualenv', 23:14:03 INFO - 'install', 23:14:03 INFO - 'run-tests'), 23:14:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:14:03 INFO - 'download_minidump_stackwalk': True, 23:14:03 INFO - 'download_symbols': 'ondemand', 23:14:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:14:03 INFO - 'tooltool.py': '/tools/tooltool.py', 23:14:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:14:03 INFO - '/tools/misc-python/virtualenv.py')}, 23:14:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:14:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:14:03 INFO - 'gecko_profile': False, 23:14:03 INFO - 'gecko_profile_interval': 0, 23:14:03 INFO - 'installer_path': 'installer.exe', 23:14:03 INFO - 'log_level': 'info', 23:14:03 INFO - 'log_name': 'talos', 23:14:03 INFO - 'log_to_console': True, 23:14:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:14:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:14:03 INFO - 'opt_config_files': (), 23:14:03 INFO - 'pip_index': False, 23:14:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:14:03 INFO - 'cmd': ('bash', 23:14:03 INFO - '-c', 23:14:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:14:03 INFO - 'enabled': True, 23:14:03 INFO - 'halt_on_failure': False, 23:14:03 INFO - 'name': 'check_screen_resolution'},), 23:14:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:14:03 INFO - 'cmd': ('bash', 23:14:03 INFO - '-c', 23:14:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:14:03 INFO - 'enabled': True, 23:14:03 INFO - 'halt_on_failure': False, 23:14:03 INFO - 'name': 'check_screen_resolution'},), 23:14:03 INFO - 'run_cmd_checks_enabled': True, 23:14:03 INFO - 'suite': 'dromaeojs-e10s', 23:14:03 INFO - 'system_bits': '32', 23:14:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:14:03 INFO - 'title': 't-yosemite-r7-0259', 23:14:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:14:03 INFO - 'use_talos_json': True, 23:14:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:14:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:14:03 INFO - 'work_dir': 'build'} 23:14:03 INFO - [mozharness: 2017-05-26 06:14:03.481225Z] Running clobber step. 23:14:03 INFO - Running pre-action listener: _resource_record_pre_action 23:14:03 INFO - Running main action method: clobber 23:14:03 INFO - rmtree: /builds/slave/test/build 23:14:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:14:05 INFO - Running post-action listener: _resource_record_post_action 23:14:05 INFO - [mozharness: 2017-05-26 06:14:05.904957Z] Finished clobber step (success) 23:14:05 INFO - [mozharness: 2017-05-26 06:14:05.905090Z] Running read-buildbot-config step. 23:14:05 INFO - Running pre-action listener: _resource_record_pre_action 23:14:05 INFO - Running main action method: read_buildbot_config 23:14:05 INFO - Using buildbot properties: 23:14:05 INFO - { 23:14:05 INFO - "project": "", 23:14:05 INFO - "product": "firefox", 23:14:05 INFO - "who": "eakhgari@mozilla.com", 23:14:05 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 23:14:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 23:14:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 23:14:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 23:14:05 INFO - "stage_platform": "macosx64", 23:14:05 INFO - "basedir": "/builds/slave/test", 23:14:05 INFO - "buildnumber": 35, 23:14:05 INFO - "platform": "yosemite_r7", 23:14:05 INFO - "parent_task_id": "DVs7eI4NRGiZL2MqYt4-yw", 23:14:05 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 23:14:05 INFO - "slavebuilddir": "test", 23:14:05 INFO - "taskId": "NwCghfKWSleI7L5WG3zdNw", 23:14:05 INFO - "branch": "mozilla-central", 23:14:05 INFO - "script_repo_revision": "production", 23:14:05 INFO - "revision": "5d6fe59a9a5dfd2d381d2cdfdc968d3305b417cf", 23:14:05 INFO - "slavename": "t-yosemite-r7-0259", 23:14:05 INFO - "repo_path": "mozilla-central" 23:14:05 INFO - } 23:14:05 INFO - Finding installer, test and symbols from parent task. 23:14:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NwCghfKWSleI7L5WG3zdNw'}, attempt #1 23:14:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NwCghfKWSleI7L5WG3zdNw'}, attempt #1 23:14:07 INFO - Task dependencies: FFPDRpuaQ-eG2p3OwSVugA DVs7eI4NRGiZL2MqYt4-yw 23:14:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw'}, attempt #1 23:14:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:14:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/test_packages.json 23:14:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/target.crashreporter-symbols.zip 23:14:07 INFO - Running post-action listener: _resource_record_post_action 23:14:07 INFO - [mozharness: 2017-05-26 06:14:07.630881Z] Finished read-buildbot-config step (success) 23:14:07 INFO - [mozharness: 2017-05-26 06:14:07.631018Z] Running download-and-extract step. 23:14:07 INFO - Running pre-action listener: _resource_record_pre_action 23:14:07 INFO - Running main action method: download_and_extract 23:14:07 INFO - mkdir: /builds/slave/test/build/tests 23:14: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')]} 23:14:07 INFO - trying https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 23:14:07 INFO - Downloading https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/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 23:14:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/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 23:14:08 INFO - Downloaded 1325 bytes. 23:14:08 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 23:14:08 INFO - Using the following test package requirements: 23:14:08 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 23:14:08 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 23:14:08 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 23:14:08 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 23:14:08 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'jsshell-mac.zip'], 23:14:08 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 23:14:08 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 23:14:08 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 23:14:08 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 23:14:08 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 23:14:08 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:14:08 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 23:14:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:14:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 23:14:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:14:08 INFO - Fetch https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 23:14:10 INFO - Content-Length response header: 57630937 23:14:10 INFO - Bytes received: 57630937 23:14:28 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 23:14:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 23:14:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:14:28 INFO - Fetch https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 23:14:29 INFO - Content-Length response header: 57630937 23:14:29 INFO - Bytes received: 57630937 23:14:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 23:14:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:14:47 INFO - Fetch https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 23:14:48 INFO - Content-Length response header: 14411888 23:14:48 INFO - Bytes received: 14411888 23:14:48 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')]} 23:14:48 INFO - trying https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:14:48 INFO - Downloading https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 23:14:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/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 23:14:50 INFO - Downloaded 67227498 bytes. 23:14:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:14:50 INFO - mkdir: /builds/slave/test/properties 23:14:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:14:50 INFO - Writing to file /builds/slave/test/properties/build_url 23:14:50 INFO - Contents: 23:14:50 INFO - build_url:https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:14:50 INFO - Running post-action listener: _resource_record_post_action 23:14:50 INFO - Running post-action listener: set_extra_try_arguments 23:14:50 INFO - [mozharness: 2017-05-26 06:14:50.622917Z] Finished download-and-extract step (success) 23:14:50 INFO - [mozharness: 2017-05-26 06:14:50.623078Z] Running populate-webroot step. 23:14:50 INFO - Running pre-action listener: _resource_record_pre_action 23:14:50 INFO - Running main action method: populate_webroot 23:14:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:14:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:14:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:14:50 INFO - '--tppagecycles', 23:14:50 INFO - '7'], 23:14:50 INFO - 'tests': ['tsvgm']}}, 23:14:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:14:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:14:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:14:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:14:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:14:50 INFO - 'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:14:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:14:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:14:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:14:50 INFO - 'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['damp', 'tps']}, 23:14:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:14:50 INFO - 'tests': ['damp', 'tps']}, 23:14:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['dromaeo_dom']}, 23:14:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:14:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 23:14:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 23:14:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 23:14:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 23:14:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['a11yr', 23:14:50 INFO - 'ts_paint', 23:14:50 INFO - 'tpaint', 23:14:50 INFO - 'sessionrestore', 23:14:50 INFO - 'sessionrestore_no_auto_restore', 23:14:50 INFO - 'tabpaint']}, 23:14:50 INFO - 'other-e10s': {'tests': ['a11yr', 23:14:50 INFO - 'ts_paint', 23:14:50 INFO - 'tpaint', 23:14:50 INFO - 'sessionrestore', 23:14:50 INFO - 'sessionrestore_no_auto_restore', 23:14:50 INFO - 'tabpaint']}, 23:14:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:14:50 INFO - 'ts_paint', 23:14:50 INFO - 'tpaint', 23:14:50 INFO - 'sessionrestore', 23:14:50 INFO - 'sessionrestore_no_auto_restore', 23:14:50 INFO - 'tabpaint']}, 23:14:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:14:50 INFO - 'ts_paint', 23:14:50 INFO - 'tpaint', 23:14:50 INFO - 'sessionrestore', 23:14:50 INFO - 'sessionrestore_no_auto_restore', 23:14:50 INFO - 'tabpaint']}, 23:14:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['a11yr', 23:14:50 INFO - 'ts_paint', 23:14:50 INFO - 'tpaint', 23:14:50 INFO - 'sessionrestore', 23:14:50 INFO - 'sessionrestore_no_auto_restore', 23:14:50 INFO - 'tabpaint']}, 23:14:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['a11yr', 23:14:50 INFO - 'ts_paint', 23:14:50 INFO - 'tpaint', 23:14:50 INFO - 'sessionrestore', 23:14:50 INFO - 'sessionrestore_no_auto_restore', 23:14:50 INFO - 'tabpaint']}, 23:14:50 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 23:14:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 23:14:50 INFO - 'bloom_basic_ref']}, 23:14:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['tsvgx', 23:14:50 INFO - 'tsvgr_opacity', 23:14:50 INFO - 'tart', 23:14:50 INFO - 'tscrollx', 23:14:50 INFO - 'cart', 23:14:50 INFO - 'tsvg_static']}, 23:14:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:14:50 INFO - 'tsvgr_opacity', 23:14:50 INFO - 'tart', 23:14:50 INFO - 'tscrollx', 23:14:50 INFO - 'cart', 23:14:50 INFO - 'tsvg_static']}, 23:14:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:14:50 INFO - 'talos_options': ['--disable-e10s'], 23:14:50 INFO - 'tests': ['tp5o']}, 23:14:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:14:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:14:50 INFO - 'talos_options': ['--disable-e10s', 23:14:50 INFO - '--xperf_path', 23:14:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:14:50 INFO - 'tests': ['tp5n']}, 23:14:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:14:50 INFO - 'talos_options': ['--xperf_path', 23:14:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:14:50 INFO - 'tests': ['tp5n']}}, 23:14:50 INFO - 'talos.zip': {'path': '', 23:14:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:14:50 INFO - Running post-action listener: _resource_record_post_action 23:14:50 INFO - [mozharness: 2017-05-26 06:14:50.644707Z] Finished populate-webroot step (success) 23:14:50 INFO - [mozharness: 2017-05-26 06:14:50.644827Z] Running create-virtualenv step. 23:14:50 INFO - Running pre-action listener: _resource_record_pre_action 23:14:50 INFO - Running main action method: create_virtualenv 23:14:50 INFO - Creating virtualenv /builds/slave/test/build/venv 23:14:50 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 23:14:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:14:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:14:50 INFO - Using real prefix '/tools/python27' 23:14:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:14:51 INFO - Installing distribute.............................................................................................................................................................................................done. 23:14:54 INFO - Installing pip.................done. 23:14:54 INFO - Return code: 0 23:14:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:14:54 INFO - Reading from file tmpfile_stdout 23:14:54 INFO - Output received: 23:14:54 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) 23:14:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:14: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')]} 23:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14: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')]} 23:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:54 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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:14:54 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 23:14:54 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 23:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:14:54 INFO - 'HOME': '/Users/cltbld', 23:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:14:54 INFO - 'LOGNAME': 'cltbld', 23:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:54 INFO - 'MOZ_NO_REMOTE': '1', 23:14:54 INFO - 'NO_EM_RESTART': '1', 23:14:54 INFO - 'PAGER': '/bin/cat', 23:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:54 INFO - 'PWD': '/builds/slave/test', 23:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:14:54 INFO - 'SHELL': '/bin/bash', 23:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:14:54 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:14:54 INFO - 'USER': 'cltbld', 23:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:54 INFO - 'XPC_FLAGS': '0x0', 23:14:54 INFO - 'XPC_SERVICE_NAME': '0', 23:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:54 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 23:14:54 INFO - Cleaning up... 23:14:54 INFO - Return code: 0 23:14:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:14: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')]} 23:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14: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')]} 23:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:54 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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:14:54 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 23:14:54 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 23:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:14:54 INFO - 'HOME': '/Users/cltbld', 23:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:14:54 INFO - 'LOGNAME': 'cltbld', 23:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:54 INFO - 'MOZ_NO_REMOTE': '1', 23:14:54 INFO - 'NO_EM_RESTART': '1', 23:14:54 INFO - 'PAGER': '/bin/cat', 23:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:54 INFO - 'PWD': '/builds/slave/test', 23:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:14:54 INFO - 'SHELL': '/bin/bash', 23:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:14:54 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:14:54 INFO - 'USER': 'cltbld', 23:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:54 INFO - 'XPC_FLAGS': '0x0', 23:14:54 INFO - 'XPC_SERVICE_NAME': '0', 23:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:14:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:55 INFO - Downloading/unpacking psutil>=3.1.1 23:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:14:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:14:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:14:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:14:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:14:57 INFO - Installing collected packages: psutil 23:14:57 INFO - Running setup.py install for psutil 23:14:57 INFO - building 'psutil._psutil_osx' extension 23:14:57 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 23:14:57 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 23:14:57 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 23:14:57 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 23:14:58 INFO - building 'psutil._psutil_posix' extension 23:14:58 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 23:14:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:14:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:14:58 INFO - ^ 23:14:58 INFO - 1 warning generated. 23:14:58 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 23:14:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:14:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:14:58 INFO - Successfully installed psutil 23:14:58 INFO - Cleaning up... 23:14:58 INFO - Return code: 0 23:14:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:14:59 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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:14:59 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 23:14:59 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 23:14:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:14:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:14:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:14:59 INFO - 'HOME': '/Users/cltbld', 23:14:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:14:59 INFO - 'LOGNAME': 'cltbld', 23:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:59 INFO - 'MOZ_NO_REMOTE': '1', 23:14:59 INFO - 'NO_EM_RESTART': '1', 23:14:59 INFO - 'PAGER': '/bin/cat', 23:14:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:59 INFO - 'PWD': '/builds/slave/test', 23:14:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:14:59 INFO - 'SHELL': '/bin/bash', 23:14:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:14:59 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:14:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:14:59 INFO - 'USER': 'cltbld', 23:14:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:14:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:59 INFO - 'XPC_FLAGS': '0x0', 23:14:59 INFO - 'XPC_SERVICE_NAME': '0', 23:14:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:14:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:14:59 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 23:14:59 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 23:14:59 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 23:14:59 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 23:15:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:15:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:15:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:15:01 INFO - Installing collected packages: mozsystemmonitor 23:15:01 INFO - Running setup.py install for mozsystemmonitor 23:15:01 INFO - Successfully installed mozsystemmonitor 23:15:01 INFO - Cleaning up... 23:15:01 INFO - Return code: 0 23:15:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:15: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')]} 23:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15: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')]} 23:15:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15: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', '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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:15: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 jsonschema==2.5.1 23:15:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:15:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:01 INFO - 'HOME': '/Users/cltbld', 23:15:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:01 INFO - 'LOGNAME': 'cltbld', 23:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:01 INFO - 'MOZ_NO_REMOTE': '1', 23:15:01 INFO - 'NO_EM_RESTART': '1', 23:15:01 INFO - 'PAGER': '/bin/cat', 23:15:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:01 INFO - 'PWD': '/builds/slave/test', 23:15:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:01 INFO - 'SHELL': '/bin/bash', 23:15:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:15:01 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:15:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:01 INFO - 'USER': 'cltbld', 23:15:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:01 INFO - 'XPC_FLAGS': '0x0', 23:15:01 INFO - 'XPC_SERVICE_NAME': '0', 23:15:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:01 INFO - Downloading/unpacking jsonschema==2.5.1 23:15:01 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 23:15:01 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 23:15:01 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 23:15:01 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 23:15:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:15:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:15:03 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 23:15:03 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 23:15:03 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 23:15:03 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 23:15:03 INFO - Downloading functools32-3.2.3-2.tar.gz 23:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:15:03 INFO - warning: no files found matching '*.txt' 23:15:03 INFO - no previously-included directories found matching 'build' 23:15:03 INFO - no previously-included directories found matching 'dist' 23:15:03 INFO - no previously-included directories found matching '.git*' 23:15:03 INFO - Installing collected packages: jsonschema, functools32 23:15:03 INFO - Running setup.py install for functools32 23:15:03 INFO - warning: no files found matching '*.txt' 23:15:03 INFO - no previously-included directories found matching 'build' 23:15:03 INFO - no previously-included directories found matching 'dist' 23:15:03 INFO - no previously-included directories found matching '.git*' 23:15:03 INFO - Successfully installed jsonschema functools32 23:15:03 INFO - Cleaning up... 23:15:03 INFO - Return code: 0 23:15:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:03 INFO - 'HOME': '/Users/cltbld', 23:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:03 INFO - 'LOGNAME': 'cltbld', 23:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:03 INFO - 'MOZ_NO_REMOTE': '1', 23:15:03 INFO - 'NO_EM_RESTART': '1', 23:15:03 INFO - 'PAGER': '/bin/cat', 23:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:03 INFO - 'PWD': '/builds/slave/test', 23:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:03 INFO - 'SHELL': '/bin/bash', 23:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:15:03 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:03 INFO - 'USER': 'cltbld', 23:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:03 INFO - 'XPC_FLAGS': '0x0', 23:15:03 INFO - 'XPC_SERVICE_NAME': '0', 23:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:15:03 INFO - Cleaning up... 23:15:03 INFO - Return code: 0 23:15:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:03 INFO - 'HOME': '/Users/cltbld', 23:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:03 INFO - 'LOGNAME': 'cltbld', 23:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:03 INFO - 'MOZ_NO_REMOTE': '1', 23:15:03 INFO - 'NO_EM_RESTART': '1', 23:15:03 INFO - 'PAGER': '/bin/cat', 23:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:03 INFO - 'PWD': '/builds/slave/test', 23:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:03 INFO - 'SHELL': '/bin/bash', 23:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:15:03 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:03 INFO - 'USER': 'cltbld', 23:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:03 INFO - 'XPC_FLAGS': '0x0', 23:15:03 INFO - 'XPC_SERVICE_NAME': '0', 23:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:04 INFO - Downloading/unpacking blobuploader==1.2.4 23:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:05 INFO - Downloading blobuploader-1.2.4.tar.gz 23:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:15:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:15:05 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 23:15:05 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 23:15:05 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 23:15:05 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 23:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:15:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:15: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 23:15: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 23:15: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 23:15: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 23:15:06 INFO - Downloading docopt-0.6.1.tar.gz 23:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:15:06 INFO - Installing collected packages: blobuploader, requests, docopt 23:15:06 INFO - Running setup.py install for blobuploader 23:15:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:15:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:15:06 INFO - Running setup.py install for requests 23:15:07 INFO - Running setup.py install for docopt 23:15:07 INFO - Successfully installed blobuploader requests docopt 23:15:07 INFO - Cleaning up... 23:15:07 INFO - Return code: 0 23:15:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15: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')]} 23:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15: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')]} 23:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:07 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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:15:07 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 23:15:07 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 23:15:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:15:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:07 INFO - 'HOME': '/Users/cltbld', 23:15:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:07 INFO - 'LOGNAME': 'cltbld', 23:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:07 INFO - 'MOZ_NO_REMOTE': '1', 23:15:07 INFO - 'NO_EM_RESTART': '1', 23:15:07 INFO - 'PAGER': '/bin/cat', 23:15:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:07 INFO - 'PWD': '/builds/slave/test', 23:15:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:07 INFO - 'SHELL': '/bin/bash', 23:15:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:15:07 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:15:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:07 INFO - 'USER': 'cltbld', 23:15:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:07 INFO - 'XPC_FLAGS': '0x0', 23:15:07 INFO - 'XPC_SERVICE_NAME': '0', 23:15:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:15:07 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-xCOXve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:15:07 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-hiDGwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:15:07 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-LUjykN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:15:07 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-zwSy9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-0_NpzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Js_YQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-zLyyMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-NmJOD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-bjRDle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-g3kXH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-BWFcAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Yt_n6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Z4b0wJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:15:08 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-tMGG0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:09 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-znNM8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:15:09 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-UpRQjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:15:09 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Yl9INh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:15:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:15:09 INFO - Running setup.py install for manifestparser 23:15:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:15:09 INFO - Running setup.py install for mozcrash 23:15:09 INFO - Running setup.py install for mozdebug 23:15:09 INFO - Running setup.py install for mozdevice 23:15:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:15:09 INFO - Running setup.py install for mozfile 23:15:10 INFO - Running setup.py install for mozhttpd 23:15:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:15:10 INFO - Running setup.py install for mozinfo 23:15:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:15:10 INFO - Running setup.py install for mozInstall 23:15:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:15:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:15:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:15:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:15:10 INFO - Running setup.py install for mozleak 23:15:10 INFO - Running setup.py install for mozlog 23:15:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:15:10 INFO - Running setup.py install for moznetwork 23:15:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:15:10 INFO - Running setup.py install for mozprocess 23:15:11 INFO - Running setup.py install for mozprofile 23:15:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:15:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:15:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:15:11 INFO - Running setup.py install for mozrunner 23:15:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:15:11 INFO - Running setup.py install for mozscreenshot 23:15:11 INFO - Running setup.py install for moztest 23:15:11 INFO - Running setup.py install for mozversion 23:15:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:15:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:15:11 INFO - Cleaning up... 23:15:11 INFO - Return code: 0 23:15:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:11 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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:15:11 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 23:15:11 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 23:15:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:15:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:11 INFO - 'HOME': '/Users/cltbld', 23:15:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:11 INFO - 'LOGNAME': 'cltbld', 23:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:11 INFO - 'MOZ_NO_REMOTE': '1', 23:15:11 INFO - 'NO_EM_RESTART': '1', 23:15:11 INFO - 'PAGER': '/bin/cat', 23:15:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:11 INFO - 'PWD': '/builds/slave/test', 23:15:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:11 INFO - 'SHELL': '/bin/bash', 23:15:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:15:11 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:15:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:11 INFO - 'USER': 'cltbld', 23:15:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:11 INFO - 'XPC_FLAGS': '0x0', 23:15:11 INFO - 'XPC_SERVICE_NAME': '0', 23:15:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:15:12 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-VLG83X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:15:12 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)) 23:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:15:12 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-eKYh78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:15:12 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)) 23:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:15:12 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ZPFbsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:15:12 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)) 23:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:15:12 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-593_h1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:15:12 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)) 23:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:15:12 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-O0GaUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:15:12 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)) 23:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:15:12 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ASFCZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:15:12 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)) 23:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:15:12 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-GwiSW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:15:12 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)) 23:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:15:12 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-gf557B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-__5lDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-upFQvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-l3seNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-vhHAo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-huHadS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-XxbhWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-t1YoUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-bs1Qkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:15:13 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)) 23:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:15:13 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-47pipU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:15:14 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)) 23:15:14 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)) 23:15:14 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)) 23:15:14 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)) 23:15:14 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)) 23:15:14 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)) 23:15:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:15 INFO - Downloading blessings-1.6.tar.gz 23:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:15:15 INFO - Installing collected packages: blessings 23:15:15 INFO - Running setup.py install for blessings 23:15:15 INFO - Successfully installed blessings 23:15:15 INFO - Cleaning up... 23:15:15 INFO - Return code: 0 23:15:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:15:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:15:16 INFO - Reading from file tmpfile_stdout 23:15:16 INFO - Current package versions: 23:15:16 INFO - blessings == 1.6 23:15:16 INFO - blobuploader == 1.2.4 23:15:16 INFO - docopt == 0.6.1 23:15:16 INFO - functools32 == 3.2.3-2 23:15:16 INFO - jsonschema == 2.5.1 23:15:16 INFO - manifestparser == 1.1 23:15:16 INFO - mozInstall == 1.12 23:15:16 INFO - mozcrash == 1.0 23:15:16 INFO - mozdebug == 0.1 23:15:16 INFO - mozdevice == 0.51 23:15:16 INFO - mozfile == 1.2 23:15:16 INFO - mozhttpd == 0.7 23:15:16 INFO - mozinfo == 0.9 23:15:16 INFO - mozleak == 0.1 23:15:16 INFO - mozlog == 3.4 23:15:16 INFO - moznetwork == 0.27 23:15:16 INFO - mozprocess == 0.25 23:15:16 INFO - mozprofile == 0.28 23:15:16 INFO - mozrunner == 6.13 23:15:16 INFO - mozscreenshot == 0.1 23:15:16 INFO - mozsystemmonitor == 0.3 23:15:16 INFO - moztest == 0.8 23:15:16 INFO - mozversion == 1.4 23:15:16 INFO - psutil == 3.1.1 23:15:16 INFO - requests == 1.2.3 23:15:16 INFO - wsgiref == 0.1.2 23:15:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:16 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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:15:16 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 23:15:16 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 23:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:16 INFO - 'HOME': '/Users/cltbld', 23:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:16 INFO - 'LOGNAME': 'cltbld', 23:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:16 INFO - 'MOZ_NO_REMOTE': '1', 23:15:16 INFO - 'NO_EM_RESTART': '1', 23:15:16 INFO - 'PAGER': '/bin/cat', 23:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:16 INFO - 'PWD': '/builds/slave/test', 23:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:16 INFO - 'SHELL': '/bin/bash', 23:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:15:16 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:16 INFO - 'USER': 'cltbld', 23:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:16 INFO - 'XPC_FLAGS': '0x0', 23:15:16 INFO - 'XPC_SERVICE_NAME': '0', 23:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:16 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)) 23:15:16 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)) 23:15:16 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)) 23:15:16 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)) 23:15:16 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)) 23:15:16 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)) 23:15:16 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)) 23:15:16 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)) 23:15:16 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)) 23:15:16 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 23:15:16 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 23:15:16 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 23:15:16 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 23:15:16 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 23:15:17 INFO - Downloading marionette_driver-1.4.0.tar.gz 23:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 23:15:17 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)) 23:15:17 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)) 23:15:17 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)) 23:15:17 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)) 23:15:17 INFO - Installing collected packages: marionette-driver 23:15:17 INFO - Running setup.py install for marionette-driver 23:15:17 INFO - Successfully installed marionette-driver 23:15:17 INFO - Cleaning up... 23:15:18 INFO - Return code: 0 23:15:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:15: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')]} 23:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15: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')]} 23:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:18 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 0x106999b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10699d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc591c965c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, '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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 23:15:18 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 23:15:18 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 23:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:18 INFO - 'HOME': '/Users/cltbld', 23:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:18 INFO - 'LOGNAME': 'cltbld', 23:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:18 INFO - 'MOZ_NO_REMOTE': '1', 23:15:18 INFO - 'NO_EM_RESTART': '1', 23:15:18 INFO - 'PAGER': '/bin/cat', 23:15:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:18 INFO - 'PWD': '/builds/slave/test', 23:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:18 INFO - 'SHELL': '/bin/bash', 23:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:15:18 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:18 INFO - 'USER': 'cltbld', 23:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:18 INFO - 'XPC_FLAGS': '0x0', 23:15:18 INFO - 'XPC_SERVICE_NAME': '0', 23:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:15:18 INFO - Cleaning up... 23:15:18 INFO - Return code: 0 23:15:18 INFO - Running post-action listener: _resource_record_post_action 23:15:18 INFO - Running post-action listener: _start_resource_monitoring 23:15:18 INFO - Starting resource monitoring. 23:15:18 INFO - [mozharness: 2017-05-26 06:15:18.365351Z] Finished create-virtualenv step (success) 23:15:18 INFO - [mozharness: 2017-05-26 06:15:18.366451Z] Running install step. 23:15:18 INFO - Running pre-action listener: _resource_record_pre_action 23:15:18 INFO - Running main action method: install 23:15:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:15:18 INFO - Reading from file tmpfile_stdout 23:15:18 INFO - Detecting whether we're running mozinstall >=1.0... 23:15:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:15:18 INFO - Reading from file tmpfile_stdout 23:15:18 INFO - Output received: 23:15:18 INFO - Usage: mozinstall [options] installer 23:15:18 INFO - Options: 23:15:18 INFO - -h, --help show this help message and exit 23:15:18 INFO - -d DEST, --destination=DEST 23:15:18 INFO - Directory to install application into. [default: 23:15:18 INFO - "/builds/slave/test"] 23:15:18 INFO - --app=APP Application being installed. [default: firefox] 23:15:18 INFO - mkdir: /builds/slave/test/build/application 23:15:18 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'] 23:15:18 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 23:15:40 INFO - Reading from file tmpfile_stdout 23:15:40 INFO - Output received: 23:15:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:15:40 INFO - Running post-action listener: _resource_record_post_action 23:15:40 INFO - [mozharness: 2017-05-26 06:15:40.145576Z] Finished install step (success) 23:15:40 INFO - [mozharness: 2017-05-26 06:15:40.145721Z] Running run-tests step. 23:15:40 INFO - Running pre-action listener: _resource_record_pre_action 23:15:40 INFO - Running main action method: run_tests 23:15:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:15:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:15:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:15:40 INFO - 2017-05-25 23:15:40.160 screenresolution[1627:7382] starting screenresolution argv=screenresolution get 23:15:40 INFO - 2017-05-25 23:15:40.163 screenresolution[1627:7382] Display 0: 1600x1200x32@60 23:15:40 INFO - 2017-05-25 23:15:40.168 screenresolution[1628:7384] starting screenresolution argv=screenresolution list 23:15:40 INFO - Available Modes on Display 0 23:15:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:15:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:15:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:15:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:15:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:15:40 INFO - Intel Iris: 23:15:40 INFO - Chipset Model: Intel Iris 23:15:40 INFO - Type: GPU 23:15:40 INFO - Bus: Built-In 23:15:40 INFO - VRAM (Dynamic, Max): 1536 MB 23:15:40 INFO - Vendor: Intel (0x8086) 23:15:40 INFO - Device ID: 0x0a2e 23:15:40 INFO - Revision ID: 0x0009 23:15:40 INFO - Displays: 23:15:40 INFO - Display: 23:15:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:15:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:15:40 INFO - Display Serial Number: 1600x1200 60 23:15:40 INFO - Main Display: Yes 23:15:40 INFO - Mirror: Off 23:15:40 INFO - Online: Yes 23:15:40 INFO - Rotation: Supported 23:15:40 INFO - Return code: 0 23:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:15:40 INFO - Python 2.7.3 23:15:40 INFO - Return code: 0 23:15:40 INFO - grabbing minidump binary from tooltool 23:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:40 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 0x1066a9d30>, '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 0x10665ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10669ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:15:40 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 23:15:40 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 23:15:40 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 23:15:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:15:40 INFO - Return code: 0 23:15:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:15:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:15:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:15:40 INFO - ENV: RUST_BACKTRACE is now 1 23:15:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:15:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:15:40 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/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0259', '--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 23:15:40 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/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0259 --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 23:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y7gYE1KVLs/Render', 23:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:15:40 INFO - 'HOME': '/Users/cltbld', 23:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:15:40 INFO - 'LOGNAME': 'cltbld', 23:15:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:15:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:40 INFO - 'MOZ_NO_REMOTE': '1', 23:15:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:15:40 INFO - 'NO_EM_RESTART': '1', 23:15:40 INFO - 'PAGER': '/bin/cat', 23:15:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:40 INFO - 'PWD': '/builds/slave/test', 23:15:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:15:40 INFO - 'RUST_BACKTRACE': '1', 23:15:40 INFO - 'SHELL': '/bin/bash', 23:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MSr1OA3UTQ/Listeners', 23:15:40 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 23:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:15:40 INFO - 'USER': 'cltbld', 23:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:40 INFO - 'XPC_FLAGS': '0x0', 23:15:40 INFO - 'XPC_SERVICE_NAME': '0', 23:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:15:40 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/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0259', '--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 23:15:40 INFO - mozversion application_buildid: 20170525221500 23:15:40 INFO - mozversion application_changeset: 5d6fe59a9a5dfd2d381d2cdfdc968d3305b417cf 23:15:40 INFO - mozversion application_display_name: Nightly 23:15:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:15:40 INFO - mozversion application_name: Firefox 23:15:40 INFO - mozversion application_remotingname: firefox 23:15:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:15:40 INFO - mozversion application_vendor: Mozilla 23:15:40 INFO - mozversion application_version: 55.0a1 23:15:40 INFO - mozversion platform_buildid: 20170525221500 23:15:40 INFO - mozversion platform_changeset: 5d6fe59a9a5dfd2d381d2cdfdc968d3305b417cf 23:15:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:15:40 INFO - mozversion platform_version: 55.0a1 23:15:40 INFO - using testdate: 1495779340 23:15:40 INFO - actual date: 1495779340 23:15:40 INFO - starting webserver on 'localhost:49222' 23:15:40 INFO - SUITE-START | Running 2 tests 23:15:40 INFO - TEST-START | dromaeo_css 23:15:40 INFO - operating with platform_type : mac_ 23:15:40 INFO - Initialising browser for dromaeo_css test... 23:15:40 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmp_9mvuj/profile 23:15:40 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 23:16:14 INFO - TEST-INFO | 1638: killed by SIGTERM 23:16:14 INFO - Browser initialized. 23:16:14 INFO - Running cycle 1/1 for dromaeo_css test... 23:16:14 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmp_9mvuj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:16:16 INFO - PID 1645 | 2017-05-25 23:16:16.285 plugin-container[1646:7865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 23:16:16 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:16:16 INFO - PID 1645 | 2017-05-25 23:16:16.285 plugin-container[1646:7865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 23:16:16 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:16:16 INFO - PID 1645 | 2017-05-25 23:16:16.285 plugin-container[1646:7865] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:16:16 INFO - PID 1645 | 2017-05-25 23:16:16.845 plugin-container[1647:7906] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 23:16:16 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:16:16 INFO - PID 1645 | 2017-05-25 23:16:16.845 plugin-container[1647:7906] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 23:16:16 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:16:16 INFO - PID 1645 | 2017-05-25 23:16:16.845 plugin-container[1647:7906] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:16:26 INFO - PID 1645 | RSS: Main: 243019776 23:16:26 INFO - PID 1645 | 23:19:28 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 23:19:30 INFO - PID 1645 | RSS: Main: 261861376 23:19:30 INFO - PID 1645 | 23:22:31 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 23:22:32 INFO - PID 1645 | RSS: Main: 270684160 23:22:32 INFO - PID 1645 | 23:25:34 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 23:25:34 INFO - PID 1645 | RSS: Main: 270782464 23:25:34 INFO - PID 1645 | 23:28:36 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 23:28:37 INFO - PID 1645 | RSS: Main: 274497536 23:28:37 INFO - PID 1645 | 23:31:39 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 23:31:40 INFO - PID 1645 | RSS: Main: 274579456 23:31:40 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 23:34:42 INFO - PID 1645 | RSS: Main: 276090880 23:34:42 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | __start_tp_report 23:34:42 INFO - PID 1645 | _x_x_mozilla_page_load 23:34:42 INFO - PID 1645 | _x_x_mozilla_page_load_details 23:34:42 INFO - PID 1645 | |i|pagename|runs| 23:34:42 INFO - PID 1645 | |0;dojo.html;3557.4841647961043;3584.4623306504004;3602.7117830573557;3613.7289703272245;3615.7830530168194;6129.908988904127;6179.289381721103;6256.624602523846;6260.060990851374;6278.623282603047;6213.689315534228;6221.377862213776;6242.126102345664;6315.273743519485;6317.1156038154695;4207.537170911197;5228.137357336035;5381.515663590276;5434.619576629628;5491.945080549203;4590.885227869313;5525.668459892419;5528.944710552883;5528.944710552903;5546.861328466801;4376.606105450493;5360.973195133999;5426.945730542683;5461.5357694596005;5469;4545.250033744428;4678.1813182692895;5494.862628434281;5521;5525.281713377275;4795.184818580849;5442.374126975409;5445.727713614304;5457.099578569531;5567.3040869891265;5339.932013597296;6166.7533298667995;6279.309275979638;6288.622682639057;6297.559170858041;5838.941610583882;6334.588251763621;6343.14367560378;6506.154199954023;6687.565308254948;6792.93207067928;6799.218089919673;6811.523193376465;6824.863502729918;6835.692393842289;6297.685115744194;6313.210848643919;6316.05259211122;6336.46140078094;6356.523260755508;6720.8319792005595;6799.456043516507;6842.897356539655;6883.414909732729;6930.029795828588;5670.773169073191;6399.488040956712;6460.935390646127;6513.478921686254;6598.86802263952;6617.636030018299;6776.356246156607;6794.014867844135;6824.2152152503095;6833.111695479556;6657;6682.264950855402;6701.262861085277;6702.229243636946;6708.396244338033;5884.47039766423;6423.940049891809;6493.448056915121;6621.569597976116;6710.966445167743;5287.3126493556365;5291.920621190685;5299;5318.22885681574;5345.465453454701;4723.622110231173;4744.0037592105355;4746.905061898743;4789.353437285991;4795.832145874878;6247;6265.122882796412;6270.6864656766975;6275.090111933835;6342.270638876496;6796.558223715443;6816.556923799937;6836.487263455261;6854.6229957351825;6873.20958089826;6324.936750632435;6343.270523889812;6349.142865713162;6355.713992870332;6356.682165891687;5637.605367624227;5757.625754325955;5918.763249470059;5919.348871624118;5969.76120955157;6925.27284635106;6946.235914049451;6952.165740111219;6976.651167441506;6989.1962424321855;5718.914216286842;6709.597424154566;6835.453163746789;6836.555623884432;6863.279355667583;6714.127163468718;6807.217170025312;6888.104546408935;6990.825229369307;6995.79012629622;6532.5427220096035;6666.666666666744;6677.399034086956;6706.329367063255;6706.429953454011;6825.317468253135;6842.0763196969765;6845.62349070786;6880.724771009303;6888.862222755617;6293.748250069946;6351.30135685065;6512;6518.706658200487;6654;6596.7031483584315;6629.105070815571;6631.204255489372;6661.666916654051;6675.566088204251;6401.967990160019;6478.093066970534;6509.4792416605615;6516.57642253252;6527.5757075789925;6364.331745166691;6437.517186210959;6603.7688680895935;6631.071649969104;6650.90023649636;6390.648514331757;6430.324815894451;6443.548951573345;6444.291127976013;6470.870582588228 23:34:42 INFO - PID 1645 | |1;ext.html;673.373762400967;769.2154002917025;829.4111181061446;1218.3299185448002;1269.5810382573752;37793.62206377939;38820.83537493876;38867;38943.831685049496;38969.41545876814;29906.40187196255;31563.68436315636;34047.319053618914;38806;38927;7815.726449574252;8018.478798878084;8336.916630833704;8561.315094792402;8568.228859402652;8467.957660211721;8480.957595212047;8521.616527256258;8549.615267312955;8570.185832345916;8512.744617661449;8575.742727718178;8581.828363432727;8582.742517724479;8596.914030859705;8509.361797865155;8552.059273479912;8552.486850788973;8560.357973152039;8566.828663426697;8353.582320883932;8421;8422.578871056423;8436.409451338408;8437.240648341618;30597.847010765025;36767;37107.62892371069;37144.25711485755;37152.81423592865;3497.1257185703575;3498.5801703795705;3530.5410296661526;3543.5216245806746;3545.6099829018785;30256.24359391011;30340.938067167765;30344.089677309716;30384.696153038407;30456.8477157615;3125.3905488429764;3151.16494129056;3165.6201255849214;3166.0976621662944;3178.55500229968;231134;234143;251658;265065;265188;46515.76742116268;46734.29898551524;46736.532634673895;46846.53153468422;46917.530824691996;48719;48837.02325953461;48902;49205.50794492009;49615.75192124016;14413.711725765426;14472.927635361966;14610.853891461162;14613.488527901685;14616.488422905148;11640;11764.117691173196;11779.411029448493;11800.232984855957;11810.40947952599;13847.861521384657;13928.955328350212;13929.23389213603;13944.721105577832;14039.649008774863;6203.844903877439;6250.531210159256;6253.499720022387;6262.1546091278005;6275.6862156891975;1004.3070281505737;1006.9043440873105;1040.1834559870617;1054.583439541377;1070.1652710885521;17264.568385790455;20276.695849562268;23093.884530577576;24186.911588978186;24511.632325515133;972.9810642915978;1079.8056349857102;1115.2583531951325;1124.4771181400688;1144.2390810111183;12231.798276310172;12356.814647780291;12401.813972790234;12411.75176496465;12413.75172496545;315010;317521;317924;318165;318806;46697.76651116791;50184;53390.73304633452;53448.19827702588;53588.196177057376;1468.5594321703659;1506.3296832909468;1527.7250094982787;1535.9539213823602;1555.922203889801;23354.766452335938;23615.52768944646;23626.409339766644;23767.64348534808;23782.286531404086;32058.83970580132;36307.63692363042;36331;36378.4543231857;36428.90713278606;268751;269641.94654694764;270560;271575;271924;33652;34905.47641785324;35099.47350789689;35138.12154696051;35161;43652;44134;44279.77860110679;44318.77840610905;44333.556664432945;27974.86012569924;28417.147485575464;29989.550156748104;30234.697653023188;30598.84700576483;29831.955881544043;29946.70053299439;30450;30543.389132216787;30566.08301750951;35454.46818297779;36847;36867.26265474729;37035.25929481449;37048.07379815418;23708.762912370654;28710.42579148447;30354.241143971576;30484.695153048186;30520.389592207586;23847.880760596086;24042.87978560096;24121.879390602935;24368.26895193147;24573.877130614233 23:34:42 INFO - PID 1645 | |2;jquery.html;391.514521992729;419.245358354961;426.3647165723074;441.856396671082;450.8345925781854;19744.308949186783;21619;22359;22408.327750167522;22454.663180052292;22692.205772797955;22737.20419785308;22819.77180228197;22827.77172228277;22836.8858155709;5960.761569537224;6260.593061451013;6286.245650521943;6289;6314.116023756677;6016.969915150418;6132.41742034506;6180.227471566054;6222.502199824025;6253.968730156343;6078.300995385543;6114.96942515289;6136.693165341738;6138.355472675372;6142.9249881270825;5774.797882074107;6046;6088.269407671063;6111.449969502743;6121.66330851803;5375.569954403638;5396.163594642837;5406.91889621656;5417.702026388547;5418.91871621926;27465;27858.721412785817;28051.29871753202;28093.438131237464;28266.770647430087;2123.787621237879;2203.3610253026604;2210.35899589119;2211.5024119572968;2219.422950032987;13936.581902542839;17591.85652932568;17725;18268.451946441626;18368.816311836978;2130.6910497977706;2150.215171462405;2154.2567814104264;2171.1206961180746;2181.9236326728487;134871.65128348788;147679;151202;151763;152939.2353038239;12613.684657883443;12782.424790884432;12834.422450989729;12861.807072893915;12954.80567791484;16488.83511164873;16525;16594.253258603392;16611.584710382336;16623.91688041552;8897.644094236159;9098.818023639491;9099.954500227457;9111.362204645611;9200.6779762708;6794.320567943166;7653.655585498666;8507.447015943944;8605.483670979762;8795.692150774692;8016.198380161937;8177.264046235868;8193.95903020493;8236.752897413087;8251.061000664839;1913.0817207740085;2198.736151661779;2199.549092436047;2199.626063569197;2329.103295231348;841.6338892581747;886.7118186589385;929.256594724218;959.69289827256;960.4285450157146;14878.70242595142;21132.47168820761;21536.353909382742;21610.35168944934;21846;916.7478943290648;987.5457289646699;997.5610731278215;1034.9016843400016;1044.9373037617768;10565.471726413649;10688.03807657299;11039.448027598748;11060.115190784718;11105.167112466597;504665;506631;508751;520242;528058;106738.9326106729;133710;136423;137254;138924.61075389318;1190.5356910804794;1226.0865655087018;1255.1339575692743;1255.7300180461245;1256.447163248168;22305.88470576432;22502.437439064153;22513.662295065915;22554.774452255268;22567;57746.711266443395;58423;58624.706876465345;58696.706516467144;59008.114878277716;69628.3037169642;75654;75736.62131689527;76186.61906690653;77099.61450192938;8809.603567839591;8836.955815220883;8854.601542930712;9041.141091596288;9110.043445438134;12264.386780660752;13818.447262109403;13904.860951390356;14097.365618547396;14315.99788014829;9653.951730241304;11605.535778569101;12069.818952715892;12100.213486123726;12193.207441516273;10825.67522974312;10991.230613856953;11037.779244415227;11367.374794386387;11407.543698251977;10633.361998280128;11945.820812687643;11952.282863028246;12043.458044387848;12060.276383417024;8598.871016934876;8675.652973880975;8700.260477859452;8727.519986400555;8821.117888211127;11728.94439500449;12012.819807702717;12072.517099315928;12121.575744848888;12125.211861228992 23:34:42 INFO - PID 1645 | |3;mootools.html;1366.166638350606;1422.2106730764428;1427.643089227693;1431.3916585451186;1437.9287430864006;11202.271852329613;11471.541138354456;11873.7625247495;12041.698957526056;12070.215435996653;11446.942765286183;11623.360715160663;11709.824352634718;11725.70685732857;11728.941355293233;2501.1746123779153;2542.8092893033004;2548.0062775517563;2557.8465292082533;2572.0869091472537;2490.0537795637633;2532.4428625702317;2534.1890595009604;2534.708508521516;2538.7842033427182;2468.518638865422;2501.312139161727;2507.5110353481077;2508.836925599839;2513.434477242615;2453.2027091195255;2468.6420469031978;2485.2171565956664;2485.9005639774387;2486.9378265543414;2442.3405680466353;2469.5307891500556;2480.503899220163;2485.005997600956;2545.9108931187507;9753.90246097537;11177.273477223955;11507.942460287644;11544.884551154464;11577.247478913843;5410.783568657288;5680;6461.903071453932;6549.214094308713;6555.967220163869;3709.5733990591157;3861.362875125601;4180.832766689329;4274.081072569413;4276.144771045772;1057.656261714938;1097.8572785537829;1238.1889862140313;1244.570623135026;1271.5676669932175;15114;15543.300551475211;15863.127527985956;16193.352265909349;16227.188640567925;5039.420466646309;5798.971005144947;5893.233879595625;5992.131140984533;6067.9975608049235;13544.458221671022;13630.659233519242;13648;13676.042677012514;13687.384067716977;6515.837104072436;6602.537822352389;6619.470442364599;6630.436412904957;6658.4673226141795;5677.80127695537;5944.346121926671;6072.423119803611;6086.726097325631;6106.664133472702;4317.697761156752;4326.956730432718;4664.180969421928;4712.293156026623;4778.546038126373;1424.266502751084;1471.7865909443285;1471.9116852988855;1491.4779827060659;1497.2064805653022;775.8874963130314;796.243568609823;807.9338908840963;811.679386642273;816.9264766170954;6134.325224225332;6162.907556386658;6180.412860778309;6235.937640623626;6282.717277722513;570.1704020649944;583.9649621022668;601.6059481039925;606.3814908793095;611;3909.615134767789;4233.97883010583;4361.912761744811;4397.912041759211;4401.2297847877135;14125.434982600786;14152.858471415155;14377.065490743069;14815.777763333559;14836.406543738129;14227.644308892153;14439.422423102958;14692;14710.55868323952;14718.852811471748;8305.211004954519;8328.375371847136;8366.414350995494;8444.831103377897;8472.19514146155;5329.706866122246;5805.767769289181;7033.714042935964;7075.929240707733;7097.964510177416;12560.995120390164;12670.049746268882;13226.206427614374;13226.867731322563;13384.93307533456;11617.941910290732;13403.396847142098;13544.187348759106;13615.931920340336;13820.861791381958;8717.564121794012;9009.369344145847;9012.188902998761;9014.729558113268;9034.503102329434;10136.949315253376;10268.127209187303;10281.691549253534;10286.022827831344;10306.072453479223;8473.025602055843;8745.56272186401;8756.168164024408;8799.208071273617;8891.021987581491;7448.73929412468;8480.279176270085;8504.787380315542;8524.37522348938;8662.480251184948;7646.541207527566;7731.188225236269;7734.1105772836845;7758.9612051939375;7764.767056988299;5747.885042299215;5793.681347525927;5965.701714914324;5977.850553736017;6011.549133815069;5005.174146265826;5661.886762264814;5751.309842818889;5935.525157987437;6074.331823499323 23:34:42 INFO - PID 1645 | |4;prototype.html;378.3397970541404;384.3638777822704;395.6834532374101;396.4845700589235;405.1208876737479;5683.403242659523;6013.887430825295;6021.668808215546;6208.751649933997;6222.782202622903;5244.5542128919;5935.465808077272;6075.726592303346;6087.634741915488;6088.756449742005;3876.2635099331037;4433.733975961452;4442;4448.354988526669;4461.665375096864;3831.8275677594575;4278.315469524877;4446.465969240607;4490.663200259977;4525.479569849476;4437.914180335238;4438.667099967499;4445.555444455545;4454.710443821157;4457.375967364555;4014.8356976476784;4411.713238639494;4430.534793846632;4436.268015777393;4450.688451808374;3174.2699179188685;3195.888143914975;3678.0253232893037;3716.5725941516803;3770.6425686753023;5941.257342832146;5975.671338076404;5993.370696076893;5994.28068631767;6010.75956961721;4734.076855013273;5299.205119232108;5303.071962406602;5303.151495760699;5319.441458646825;5022.253969998353;5314.415414304423;5846.7953621623465;6409.359064093554;6469.417752402306;4006.699497537667;4277.866365413168;4314.9784251078545;4317.201317756225;4351.390805287262;5393.892122157574;6213.533984951102;6601.768938087192;6840.589564626089;7002.614856182908;4226.36604509326;4333.653307735437;4348.239058164809;4359.237133501624;4373.781310934441;4235.618794308527;4238.978805106017;4422.624076953431;4444.1111777643955;4476.820927162942;5089.389273287229;5230.712310822942;5255.868603284948;5256.763445644955;5261.973690131525;6422.775202867877;6509.349065093453;6515.902261465987;6525.249596296487;6540.9345906541275;1359.9252041137831;1428.0788891165348;1496.9625759355886;1503;1515.8711509521813;953.1977934153292;990.346371394876;998.9051040151174;1002.3234316836107;1007.8185926533295;5837.270341207349;6061.727037322212;6100.206973093558;6125.908111378333;6151.96924015377;6355.682215889187;6763.458923286125;6779.423748981293;6782.152230971129;6807.591544507345;4895.290182923456;6047.848803779941;6116.052011938156;6124.173236613089;6127;3021.3655132422;3540.592831824322;3564.269324788412;3573.446115852047;3577.799653213255;6717.5297729159465;6878.312168783082;6881.243063263037;6969.815131427669;6976.93023069773;5641.464060914206;6037.305709843425;6439.3238709934785;6723.294054124344;6997.860042799116;5869.918292896264;6499.707513161919;6881.311868813079;7020.789376318617;7024.014793712651;6594.65889609201;6785.728570857111;6825.931740682729;6833.384995350442;6877.4841886857685;6635.104260924906;6684.4318232950745;6717.865642687217;6746.831329216809;6751.426128779109;6824.556403833735;6865.313468653295;6896.344847239504;6927.515073944775;6963.79108626742;4138.6482149017675;4305.418768466342;4369.825206991684;4372.125574884972;4385.824567017284;6114.48026917717;6339.683015849281;6807.128039789098;6822.488313376417;6822.658867056726;6343.397377249154;6550.934490655032;6596.769113081019;6598.40614344692;6613.5370524062855;5322.813701520428;5861.3845546217035;6584.979328204136;6589.7364105435245;6603.438707709898;5058.747062646927;6399.880020996214;6466.482681385572;6468.708908099053;6500.414962653384;5719.399463056486;5823;6580.539362244788;6608.471322294301;6636.568623039487;5414.918776218281;5673.872218004628;5881.852953676193;6336.922723136986;6429.903551446826 23:34:42 INFO - PID 1645 | |5;yui.html;743.2344684974477;821.6713314674133;832.3881946769127;855.6962278391171;865.1996902864851;14728.18994955277;15141.54575362738;16527.75208371874;17027.91486042571;17075.48773536793;16014.83985160148;17341.826581734178;17580.208890599923;17629.29482820689;17659.91170044148;985.7092157813462;1020.8162530744463;1025.646152077532;1039.974000649986;1044.650042235848;1032.9431881246528;1036.7356324137334;1037.9325343852624;1039.0907955538903;1040.1470793948965;956.2636769687336;1059.0838924330437;1064.834950582657;1069.6256310291415;1070.4326706845345;1049.228816819637;1053.310081896363;1055.8046761349176;1058.0688993685585;1058.9682309530726;771.1748429180778;782.2568559868091;783.2637320918318;784.2862994674819;787.3897729259812;17094.91452542729;17200.009951981614;17294;17319.393821216196;17533.123343832758;17564.12179391038;18031.459056228334;18124.18441170137;18127.36554220609;18165.182566784395;14248.572542823731;14651.194184319858;14681.486147984877;14696.779548306784;14807.185604791732;3624.4200927851416;3634.4184930411006;3637.8362973665985;3667.156553992597;3671.0638787109383;151057.48942510545;152751;154665;155479;157188;13523.526676566273;13581.981351398486;13582.388792504164;13735;13793.448262069605;26125.477490450085;26309.736902630728;26530.867345663533;26845.060422885104;27031.1890643281;6787.321267873173;7017.47368947331;7759.650815713307;7826.569538675428;7856.960715196502;3976.224636195914;4082.775447350424;4086.7547947123267;4088.2845502037026;4095.0990782027907;3674.7243956703355;4145.66834653227;4192.559781222928;4195;4245.957540424618;890.7060669978891;903.2593273515654;907.5144797533288;908.1145882764253;908.2007833106956;634.2420807135521;640.766120366063;674.2381109346505;681.465049935798;681.8329509270161;9703.66037188695;9887.505624718735;11185.42735170899;11721.88278117225;11975;650.8600650860088;660.9504287178481;667.9398854103154;680.6562685843717;681.7750142452978;5836.620619659709;6282.528810339151;6317.957537006434;6435;6480.708368123352;129688;135928;136457;137934;139906;80750.59624701839;99840.00159998452;102222;108434.45782771036;118060.50667213385;74690;86777;92035.079649204;94427;95661.0433895666;10357.4303413313;11639.185257032264;12692.682682933;12928.806067909178;12941.352932353533;35259.647403526666;40453.39319910145;41286.79356603198;41378.793106034274;42016;40822.59177408188;47261;53103.937921240584;53558;53750;14688.632784180481;16464.506064818073;17016.23426945766;17080.57298567546;17092.230849612046;15647.687046258783;18593.53516162107;18739.156737947098;19045.04774761284;19201.32795352156;14896.851031489547;17492.737608935622;17943.910280449036;17977.460676179737;18166.90916545409;16864.915675422035;16878.57803554921;17052.829471705125;17484;17604.38384656531;17048;17210.741838872178;17318.740218896473;17338.393156239472;17564.297428103244;10421.426821524889;10621.415822129857;11896.702582435508;12126.27242365461;12258.632241032783;11749;13284.734305314032;13376.933115334361;13417.597472075853;13446.462141514621 23:34:42 INFO - PID 1645 | __end_tp_report 23:34:42 INFO - PID 1645 | __start_cc_report 23:34:42 INFO - PID 1645 | _x_x_mozilla_cycle_collect,395 23:34:42 INFO - PID 1645 | __end_cc_report 23:34:42 INFO - PID 1645 | __startTimestamp1495780482559__endTimestamp 23:34:42 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | ------- Summary: start ------- 23:34:42 INFO - PID 1645 | Number of tests: 6 23:34:42 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | [#0] dojo.html Cycles:165 Average:6144.47 Median:6356.68 stddev:782.39 (12.3%) stddev-sans-first:758.01 23:34:42 INFO - PID 1645 | Values: 3557.5 3584.5 3602.7 3613.7 3615.8 6129.9 6179.3 6256.6 6260.1 6278.6 6213.7 6221.4 6242.1 6315.3 6317.1 4207.5 5228.1 5381.5 5434.6 5491.9 4590.9 5525.7 5528.9 5528.9 5546.9 4376.6 5361.0 5426.9 5461.5 5469.0 4545.3 4678.2 5494.9 5521.0 5525.3 4795.2 5442.4 5445.7 5457.1 5567.3 5339.9 6166.8 6279.3 6288.6 6297.6 5838.9 6334.6 6343.1 6506.2 6687.6 6792.9 6799.2 6811.5 6824.9 6835.7 6297.7 6313.2 6316.1 6336.5 6356.5 6720.8 6799.5 6842.9 6883.4 6930.0 5670.8 6399.5 6460.9 6513.5 6598.9 6617.6 6776.4 6794.0 6824.2 6833.1 6657.0 6682.3 6701.3 6702.2 6708.4 5884.5 6423.9 6493.4 6621.6 6711.0 5287.3 5291.9 5299.0 5318.2 5345.5 4723.6 4744.0 4746.9 4789.4 4795.8 6247.0 6265.1 6270.7 6275.1 6342.3 6796.6 6816.6 6836.5 6854.6 6873.2 6324.9 6343.3 6349.1 6355.7 6356.7 5637.6 5757.6 5918.8 5919.3 5969.8 6925.3 6946.2 6952.2 6976.7 6989.2 5718.9 6709.6 6835.5 6836.6 6863.3 6714.1 6807.2 6888.1 6990.8 6995.8 6532.5 6666.7 6677.4 6706.3 6706.4 6825.3 6842.1 6845.6 6880.7 6888.9 6293.7 6351.3 6512.0 6518.7 6654.0 6596.7 6629.1 6631.2 6661.7 6675.6 6402.0 6478.1 6509.5 6516.6 6527.6 6364.3 6437.5 6603.8 6631.1 6650.9 6390.6 6430.3 6443.5 6444.3 6470.9 23:34:42 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | [#1] ext.html Cycles:180 Average:42938.87 Median:160551.28 stddev:73485.27 (45.8%) stddev-sans-first:73622.90 23:34:42 INFO - PID 1645 | Values: 673.4 769.2 829.4 1218.3 1269.6 37793.6 38820.8 38867.0 38943.8 38969.4 29906.4 31563.7 34047.3 38806.0 38927.0 7815.7 8018.5 8336.9 8561.3 8568.2 8468.0 8481.0 8521.6 8549.6 8570.2 8512.7 8575.7 8581.8 8582.7 8596.9 8509.4 8552.1 8552.5 8560.4 8566.8 8353.6 8421.0 8422.6 8436.4 8437.2 30597.8 36767.0 37107.6 37144.3 37152.8 3497.1 3498.6 3530.5 3543.5 3545.6 30256.2 30340.9 30344.1 30384.7 30456.8 3125.4 3151.2 3165.6 3166.1 3178.6 231134.0 234143.0 251658.0 265065.0 265188.0 46515.8 46734.3 46736.5 46846.5 46917.5 48719.0 48837.0 48902.0 49205.5 49615.8 14413.7 14472.9 14610.9 14613.5 14616.5 11640.0 11764.1 11779.4 11800.2 11810.4 13847.9 13929.0 13929.2 13944.7 14039.6 6203.8 6250.5 6253.5 6262.2 6275.7 1004.3 1006.9 1040.2 1054.6 1070.2 17264.6 20276.7 23093.9 24186.9 24511.6 973.0 1079.8 1115.3 1124.5 1144.2 12231.8 12356.8 12401.8 12411.8 12413.8 315010.0 317521.0 317924.0 318165.0 318806.0 46697.8 50184.0 53390.7 53448.2 53588.2 1468.6 1506.3 1527.7 1536.0 1555.9 23354.8 23615.5 23626.4 23767.6 23782.3 32058.8 36307.6 36331.0 36378.5 36428.9 268751.0 269641.9 270560.0 271575.0 271924.0 33652.0 34905.5 35099.5 35138.1 35161.0 43652.0 44134.0 44279.8 44318.8 44333.6 27974.9 28417.1 29989.6 30234.7 30598.8 29832.0 29946.7 30450.0 30543.4 30566.1 35454.5 36847.0 36867.3 37035.3 37048.1 23708.8 28710.4 30354.2 30484.7 30520.4 23847.9 24042.9 24121.9 24368.3 24573.9 23:34:42 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | [#2] jquery.html Cycles:180 Average:34471.66 Median:22714.70 stddev:87500.64 (385.2%) stddev-sans-first:87708.69 23:34:42 INFO - PID 1645 | Values: 391.5 419.2 426.4 441.9 450.8 19744.3 21619.0 22359.0 22408.3 22454.7 22692.2 22737.2 22819.8 22827.8 22836.9 5960.8 6260.6 6286.2 6289.0 6314.1 6017.0 6132.4 6180.2 6222.5 6254.0 6078.3 6115.0 6136.7 6138.4 6142.9 5774.8 6046.0 6088.3 6111.4 6121.7 5375.6 5396.2 5406.9 5417.7 5418.9 27465.0 27858.7 28051.3 28093.4 28266.8 2123.8 2203.4 2210.4 2211.5 2219.4 13936.6 17591.9 17725.0 18268.5 18368.8 2130.7 2150.2 2154.3 2171.1 2181.9 134871.7 147679.0 151202.0 151763.0 152939.2 12613.7 12782.4 12834.4 12861.8 12954.8 16488.8 16525.0 16594.3 16611.6 16623.9 8897.6 9098.8 9100.0 9111.4 9200.7 6794.3 7653.7 8507.4 8605.5 8795.7 8016.2 8177.3 8194.0 8236.8 8251.1 1913.1 2198.7 2199.5 2199.6 2329.1 841.6 886.7 929.3 959.7 960.4 14878.7 21132.5 21536.4 21610.4 21846.0 916.7 987.5 997.6 1034.9 1044.9 10565.5 10688.0 11039.4 11060.1 11105.2 504665.0 506631.0 508751.0 520242.0 528058.0 106738.9 133710.0 136423.0 137254.0 138924.6 1190.5 1226.1 1255.1 1255.7 1256.4 22305.9 22502.4 22513.7 22554.8 22567.0 57746.7 58423.0 58624.7 58696.7 59008.1 69628.3 75654.0 75736.6 76186.6 77099.6 8809.6 8837.0 8854.6 9041.1 9110.0 12264.4 13818.4 13904.9 14097.4 14316.0 9654.0 11605.5 12069.8 12100.2 12193.2 10825.7 10991.2 11037.8 11367.4 11407.5 10633.4 11945.8 11952.3 12043.5 12060.3 8598.9 8675.7 8700.3 8727.5 8821.1 11728.9 12012.8 12072.5 12121.6 12125.2 23:34:42 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | [#3] mootools.html Cycles:180 Average:6976.00 Median:3885.49 stddev:4421.36 (113.8%) stddev-sans-first:4413.67 23:34:42 INFO - PID 1645 | Values: 1366.2 1422.2 1427.6 1431.4 1437.9 11202.3 11471.5 11873.8 12041.7 12070.2 11446.9 11623.4 11709.8 11725.7 11728.9 2501.2 2542.8 2548.0 2557.8 2572.1 2490.1 2532.4 2534.2 2534.7 2538.8 2468.5 2501.3 2507.5 2508.8 2513.4 2453.2 2468.6 2485.2 2485.9 2486.9 2442.3 2469.5 2480.5 2485.0 2545.9 9753.9 11177.3 11507.9 11544.9 11577.2 5410.8 5680.0 6461.9 6549.2 6556.0 3709.6 3861.4 4180.8 4274.1 4276.1 1057.7 1097.9 1238.2 1244.6 1271.6 15114.0 15543.3 15863.1 16193.4 16227.2 5039.4 5799.0 5893.2 5992.1 6068.0 13544.5 13630.7 13648.0 13676.0 13687.4 6515.8 6602.5 6619.5 6630.4 6658.5 5677.8 5944.3 6072.4 6086.7 6106.7 4317.7 4327.0 4664.2 4712.3 4778.5 1424.3 1471.8 1471.9 1491.5 1497.2 775.9 796.2 807.9 811.7 816.9 6134.3 6162.9 6180.4 6235.9 6282.7 570.2 584.0 601.6 606.4 611.0 3909.6 4234.0 4361.9 4397.9 4401.2 14125.4 14152.9 14377.1 14815.8 14836.4 14227.6 14439.4 14692.0 14710.6 14718.9 8305.2 8328.4 8366.4 8444.8 8472.2 5329.7 5805.8 7033.7 7075.9 7098.0 12561.0 12670.0 13226.2 13226.9 13384.9 11617.9 13403.4 13544.2 13615.9 13820.9 8717.6 9009.4 9012.2 9014.7 9034.5 10136.9 10268.1 10281.7 10286.0 10306.1 8473.0 8745.6 8756.2 8799.2 8891.0 7448.7 8480.3 8504.8 8524.4 8662.5 7646.5 7731.2 7734.1 7759.0 7764.8 5747.9 5793.7 5965.7 5977.9 6011.5 5005.2 5661.9 5751.3 5935.5 6074.3 23:34:42 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | [#4] prototype.html Cycles:180 Average:5226.99 Median:5938.36 stddev:1668.26 (28.1%) stddev-sans-first:1632.76 23:34:42 INFO - PID 1645 | Values: 378.3 384.4 395.7 396.5 405.1 5683.4 6013.9 6021.7 6208.8 6222.8 5244.6 5935.5 6075.7 6087.6 6088.8 3876.3 4433.7 4442.0 4448.4 4461.7 3831.8 4278.3 4446.5 4490.7 4525.5 4437.9 4438.7 4445.6 4454.7 4457.4 4014.8 4411.7 4430.5 4436.3 4450.7 3174.3 3195.9 3678.0 3716.6 3770.6 5941.3 5975.7 5993.4 5994.3 6010.8 4734.1 5299.2 5303.1 5303.2 5319.4 5022.3 5314.4 5846.8 6409.4 6469.4 4006.7 4277.9 4315.0 4317.2 4351.4 5393.9 6213.5 6601.8 6840.6 7002.6 4226.4 4333.7 4348.2 4359.2 4373.8 4235.6 4239.0 4422.6 4444.1 4476.8 5089.4 5230.7 5255.9 5256.8 5262.0 6422.8 6509.3 6515.9 6525.2 6540.9 1359.9 1428.1 1497.0 1503.0 1515.9 953.2 990.3 998.9 1002.3 1007.8 5837.3 6061.7 6100.2 6125.9 6152.0 6355.7 6763.5 6779.4 6782.2 6807.6 4895.3 6047.8 6116.1 6124.2 6127.0 3021.4 3540.6 3564.3 3573.4 3577.8 6717.5 6878.3 6881.2 6969.8 6976.9 5641.5 6037.3 6439.3 6723.3 6997.9 5869.9 6499.7 6881.3 7020.8 7024.0 6594.7 6785.7 6825.9 6833.4 6877.5 6635.1 6684.4 6717.9 6746.8 6751.4 6824.6 6865.3 6896.3 6927.5 6963.8 4138.6 4305.4 4369.8 4372.1 4385.8 6114.5 6339.7 6807.1 6822.5 6822.7 6343.4 6550.9 6596.8 6598.4 6613.5 5322.8 5861.4 6585.0 6589.7 6603.4 5058.7 6399.9 6466.5 6468.7 6500.4 5719.4 5823.0 6580.5 6608.5 6636.6 5414.9 5673.9 5881.9 6336.9 6429.9 23:34:42 INFO - PID 1645 | 23:34:42 INFO - PID 1645 | [#5] yui.html Cycles:180 Average:23932.37 Median:17616.84 stddev:36900.30 (209.5%) stddev-sans-first:36962.74 23:34:42 INFO - PID 1645 | Values: 743.2 821.7 832.4 855.7 865.2 14728.2 15141.5 16527.8 17027.9 17075.5 16014.8 17341.8 17580.2 17629.3 17659.9 985.7 1020.8 1025.6 1040.0 1044.7 1032.9 1036.7 1037.9 1039.1 1040.1 956.3 1059.1 1064.8 1069.6 1070.4 1049.2 1053.3 1055.8 1058.1 1059.0 771.2 782.3 783.3 784.3 787.4 17094.9 17200.0 17294.0 17319.4 17533.1 17564.1 18031.5 18124.2 18127.4 18165.2 14248.6 14651.2 14681.5 14696.8 14807.2 3624.4 3634.4 3637.8 3667.2 3671.1 151057.5 152751.0 154665.0 155479.0 157188.0 13523.5 13582.0 13582.4 13735.0 13793.4 26125.5 26309.7 26530.9 26845.1 27031.2 6787.3 7017.5 7759.7 7826.6 7857.0 3976.2 4082.8 4086.8 4088.3 4095.1 3674.7 4145.7 4192.6 4195.0 4246.0 890.7 903.3 907.5 908.1 908.2 634.2 640.8 674.2 681.5 681.8 9703.7 9887.5 11185.4 11721.9 11975.0 650.9 661.0 667.9 680.7 681.8 5836.6 6282.5 6318.0 6435.0 6480.7 129688.0 135928.0 136457.0 137934.0 139906.0 80750.6 99840.0 102222.0 108434.5 118060.5 74690.0 86777.0 92035.1 94427.0 95661.0 10357.4 11639.2 12692.7 12928.8 12941.4 35259.6 40453.4 41286.8 41378.8 42016.0 40822.6 47261.0 53103.9 53558.0 53750.0 14688.6 16464.5 17016.2 17080.6 17092.2 15647.7 18593.5 18739.2 19045.0 19201.3 14896.9 17492.7 17943.9 17977.5 18166.9 16864.9 16878.6 17052.8 17484.0 17604.4 17048.0 17210.7 17318.7 17338.4 17564.3 10421.4 10621.4 11896.7 12126.3 12258.6 11749.0 13284.7 13376.9 13417.6 13446.5 23:34:42 INFO - PID 1645 | -------- Summary: end -------- 23:34:42 INFO - PID 1645 | 23:34:43 INFO - TEST-INFO | 1645: exit 0 23:34:43 INFO - TEST-OK | dromaeo_css | took 1142270ms 23:34:43 INFO - TEST-START | kraken 23:34:43 INFO - operating with platform_type : mac_ 23:34:43 INFO - Initialising browser for kraken test... 23:34:43 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpho8MCe/profile 23:34:43 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 23:35:16 INFO - TEST-INFO | 1657: killed by SIGTERM 23:35:16 INFO - Browser initialized. 23:35:16 INFO - Running cycle 1/1 for kraken test... 23:35:16 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpho8MCe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:35:18 INFO - PID 1661 | 2017-05-25 23:35:18.627 plugin-container[1662:10584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453b, name = 'com.apple.tsm.portname' 23:35:18 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:35:18 INFO - PID 1661 | 2017-05-25 23:35:18.627 plugin-container[1662:10584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.CFPasteboardClient' 23:35:18 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:35:18 INFO - PID 1661 | 2017-05-25 23:35:18.627 plugin-container[1662:10584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:35:19 INFO - PID 1661 | 2017-05-25 23:35:19.205 plugin-container[1663:10625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 23:35:19 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:35:19 INFO - PID 1661 | 2017-05-25 23:35:19.206 plugin-container[1663:10625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 23:35:19 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:35:19 INFO - PID 1661 | 2017-05-25 23:35:19.206 plugin-container[1663:10625] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:35:28 INFO - PID 1661 | RSS: Main: 242577408 23:35:28 INFO - PID 1661 | 23:35:31 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 23:35:32 INFO - PID 1661 | RSS: Main: 257200128 23:35:32 INFO - PID 1661 | 23:35:34 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 23:35:35 INFO - PID 1661 | RSS: Main: 266358784 23:35:35 INFO - PID 1661 | 23:35:38 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 23:35:39 INFO - PID 1661 | RSS: Main: 268750848 23:35:39 INFO - PID 1661 | 23:35:41 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 23:35:41 INFO - PID 1661 | RSS: Main: 271159296 23:35:41 INFO - PID 1661 | 23:35:43 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:35:44 INFO - PID 1661 | RSS: Main: 271945728 23:35:44 INFO - PID 1661 | 23:35:47 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 23:35:47 INFO - PID 1661 | RSS: Main: 277037056 23:35:47 INFO - PID 1661 | 23:35:51 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 23:35:51 INFO - PID 1661 | RSS: Main: 279965696 23:35:51 INFO - PID 1661 | 23:35:54 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 23:35:55 INFO - PID 1661 | RSS: Main: 278560768 23:35:55 INFO - PID 1661 | 23:35:56 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:35:57 INFO - PID 1661 | RSS: Main: 278274048 23:35:57 INFO - PID 1661 | 23:35:59 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:35:59 INFO - PID 1661 | RSS: Main: 278151168 23:35:59 INFO - PID 1661 | 23:36:02 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:36:02 INFO - PID 1661 | RSS: Main: 278061056 23:36:02 INFO - PID 1661 | 23:36:05 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:36:06 INFO - PID 1661 | RSS: Main: 277946368 23:36:06 INFO - PID 1661 | 23:36:09 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:36:10 INFO - PID 1661 | RSS: Main: 277856256 23:36:10 INFO - PID 1661 | 23:36:11 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 23:36:12 INFO - PID 1661 | RSS: Main: 279744512 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | __start_tp_report 23:36:12 INFO - PID 1661 | _x_x_mozilla_page_load 23:36:12 INFO - PID 1661 | _x_x_mozilla_page_load_details 23:36:12 INFO - PID 1661 | |i|pagename|runs| 23:36:12 INFO - PID 1661 | |0;ai-astar;102;116;115;114;113;113;112;115;116;115 23:36:12 INFO - PID 1661 | |1;audio-beat-detection;149;122;145;151;109;121;98;95;142;143 23:36:12 INFO - PID 1661 | |2;audio-dft;199;201;196;210;218;214;211;217;202;196 23:36:12 INFO - PID 1661 | |3;audio-fft;82;60;56;83;62;74;65;83;79;60 23:36:12 INFO - PID 1661 | |4;audio-oscillator;81;69;84;92;102;77;84;69;72;90 23:36:12 INFO - PID 1661 | |5;imaging-gaussian-blur;91;92;91;90;92;91;91;91;103;90 23:36:12 INFO - PID 1661 | |6;imaging-darkroom;98;105;98;98;107;96;97;112;151;135 23:36:12 INFO - PID 1661 | |7;imaging-desaturate;93;94;91;94;93;98;91;91;92;93 23:36:12 INFO - PID 1661 | |8;json-parse-financial;52;49;52;52;53;52;52;51;52;52 23:36:12 INFO - PID 1661 | |9;json-stringify-tinderbox;57;63;61;71;85;58;58;56;61;61 23:36:12 INFO - PID 1661 | |10;stanford-crypto-aes;86;85;87;90;89;87;78;112;146;87 23:36:12 INFO - PID 1661 | |11;stanford-crypto-ccm;149;169;130;137;172;154;142;142;137;139 23:36:12 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;193;189;187;190;238;185;191;188;176;194 23:36:12 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;75;91;96;94;71;64;100;93;92;71 23:36:12 INFO - PID 1661 | __end_tp_report 23:36:12 INFO - PID 1661 | __start_cc_report 23:36:12 INFO - PID 1661 | _x_x_mozilla_cycle_collect,1051 23:36:12 INFO - PID 1661 | __end_cc_report 23:36:12 INFO - PID 1661 | __startTimestamp1495780572176__endTimestamp 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | ------- Summary: start ------- 23:36:12 INFO - PID 1661 | Number of tests: 14 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:113.10 Median:115.00 stddev:4.12 (3.6%) stddev-sans-first:1.41 23:36:12 INFO - PID 1661 | Values: 102.0 116.0 115.0 114.0 113.0 113.0 112.0 115.0 116.0 115.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:147.00 stddev:21.38 (14.5%) stddev-sans-first:21.21 23:36:12 INFO - PID 1661 | Values: 149.0 122.0 145.0 151.0 109.0 121.0 98.0 95.0 142.0 143.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:206.40 Median:210.50 stddev:8.55 (4.1%) stddev-sans-first:8.64 23:36:12 INFO - PID 1661 | Values: 199.0 201.0 196.0 210.0 218.0 214.0 211.0 217.0 202.0 196.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:70.40 Median:76.50 stddev:10.86 (14.2%) stddev-sans-first:10.68 23:36:12 INFO - PID 1661 | Values: 82.0 60.0 56.0 83.0 62.0 74.0 65.0 83.0 79.0 60.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:82.50 stddev:10.73 (13.0%) stddev-sans-first:11.37 23:36:12 INFO - PID 1661 | Values: 81.0 69.0 84.0 92.0 102.0 77.0 84.0 69.0 72.0 90.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:91.00 stddev:3.85 (4.2%) stddev-sans-first:4.06 23:36:12 INFO - PID 1661 | Values: 91.0 92.0 91.0 90.0 92.0 91.0 91.0 91.0 103.0 90.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:109.70 Median:96.50 stddev:18.68 (19.4%) stddev-sans-first:19.33 23:36:12 INFO - PID 1661 | Values: 98.0 105.0 98.0 98.0 107.0 96.0 97.0 112.0 151.0 135.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:93.00 stddev:2.11 (2.3%) stddev-sans-first:2.24 23:36:12 INFO - PID 1661 | Values: 93.0 94.0 91.0 94.0 93.0 98.0 91.0 91.0 92.0 93.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:1.06 (2.0%) stddev-sans-first:1.12 23:36:12 INFO - PID 1661 | Values: 52.0 49.0 52.0 52.0 53.0 52.0 52.0 51.0 52.0 52.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:61.00 stddev:8.79 (14.4%) stddev-sans-first:9.04 23:36:12 INFO - PID 1661 | Values: 57.0 63.0 61.0 71.0 85.0 58.0 58.0 56.0 61.0 61.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:94.70 Median:87.00 stddev:20.03 (23.0%) stddev-sans-first:21.00 23:36:12 INFO - PID 1661 | Values: 86.0 85.0 87.0 90.0 89.0 87.0 78.0 112.0 146.0 87.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:145.50 stddev:14.00 (9.6%) stddev-sans-first:14.84 23:36:12 INFO - PID 1661 | Values: 149.0 169.0 130.0 137.0 172.0 154.0 142.0 142.0 137.0 139.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.10 Median:190.50 stddev:16.56 (8.7%) stddev-sans-first:17.57 23:36:12 INFO - PID 1661 | Values: 193.0 189.0 187.0 190.0 238.0 185.0 191.0 188.0 176.0 194.0 23:36:12 INFO - PID 1661 | 23:36:12 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.70 Median:91.50 stddev:12.94 (14.1%) stddev-sans-first:13.25 23:36:12 INFO - PID 1661 | Values: 75.0 91.0 96.0 94.0 71.0 64.0 100.0 93.0 92.0 71.0 23:36:12 INFO - PID 1661 | -------- Summary: end -------- 23:36:12 INFO - PID 1661 | 23:36:12 INFO - TEST-INFO | 1661: exit 0 23:36:12 INFO - TEST-OK | kraken | took 89847ms 23:36:12 INFO - SUITE-END | took 1232s 23:36:13 INFO - Completed test suite (00:20:32) 23:36:13 INFO - javascript benchmark 23:36:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7847.946462542634, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3557.4841647961043, 3584.4623306504004, 3602.7117830573557, 3613.7289703272245, 3615.7830530168194, 6129.908988904127, 6179.289381721103, 6256.624602523846, 6260.060990851374, 6278.623282603047, 6213.689315534228, 6221.377862213776, 6242.126102345664, 6315.273743519485, 6317.1156038154695, 4207.537170911197, 5228.137357336035, 5381.515663590276, 5434.619576629628, 5491.945080549203, 4590.885227869313, 5525.668459892419, 5528.944710552883, 5528.944710552903, 5546.861328466801, 4376.606105450493, 5360.973195133999, 5426.945730542683, 5461.5357694596005, 5469.0, 4545.250033744428, 4678.1813182692895, 5494.862628434281, 5521.0, 5525.281713377275, 4795.184818580849, 5442.374126975409, 5445.727713614304, 5457.099578569531, 5567.3040869891265, 5339.932013597296, 6166.7533298667995, 6279.309275979638, 6288.622682639057, 6297.559170858041, 5838.941610583882, 6334.588251763621, 6343.14367560378, 6506.154199954023, 6687.565308254948, 6792.93207067928, 6799.218089919673, 6811.523193376465, 6824.863502729918, 6835.692393842289, 6297.685115744194, 6313.210848643919, 6316.05259211122, 6336.46140078094, 6356.523260755508, 6720.8319792005595, 6799.456043516507, 6842.897356539655, 6883.414909732729, 6930.029795828588, 5670.773169073191, 6399.488040956712, 6460.935390646127, 6513.478921686254, 6598.86802263952, 6617.636030018299, 6776.356246156607, 6794.014867844135, 6824.2152152503095, 6833.111695479556, 6657.0, 6682.264950855402, 6701.262861085277, 6702.229243636946, 6708.396244338033, 5884.47039766423, 6423.940049891809, 6493.448056915121, 6621.569597976116, 6710.966445167743, 5287.3126493556365, 5291.920621190685, 5299.0, 5318.22885681574, 5345.465453454701, 4723.622110231173, 4744.0037592105355, 4746.905061898743, 4789.353437285991, 4795.832145874878, 6247.0, 6265.122882796412, 6270.6864656766975, 6275.090111933835, 6342.270638876496, 6796.558223715443, 6816.556923799937, 6836.487263455261, 6854.6229957351825, 6873.20958089826, 6324.936750632435, 6343.270523889812, 6349.142865713162, 6355.713992870332, 6356.682165891687, 5637.605367624227, 5757.625754325955, 5918.763249470059, 5919.348871624118, 5969.76120955157, 6925.27284635106, 6946.235914049451, 6952.165740111219, 6976.651167441506, 6989.1962424321855, 5718.914216286842, 6709.597424154566, 6835.453163746789, 6836.555623884432, 6863.279355667583, 6714.127163468718, 6807.217170025312, 6888.104546408935, 6990.825229369307, 6995.79012629622, 6532.5427220096035, 6666.666666666744, 6677.399034086956, 6706.329367063255, 6706.429953454011, 6825.317468253135, 6842.0763196969765, 6845.62349070786, 6880.724771009303, 6888.862222755617, 6293.748250069946, 6351.30135685065, 6512.0, 6518.706658200487, 6654.0, 6596.7031483584315, 6629.105070815571, 6631.204255489372, 6661.666916654051, 6675.566088204251, 6401.967990160019, 6478.093066970534, 6509.4792416605615, 6516.57642253252, 6527.5757075789925, 6364.331745166691, 6437.517186210959, 6603.7688680895935, 6631.071649969104, 6650.90023649636, 6390.648514331757, 6430.324815894451, 6443.548951573345, 6444.291127976013, 6470.870582588228], "value": 6091.03835454586, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.373762400967, 769.2154002917025, 829.4111181061446, 1218.3299185448002, 1269.5810382573752, 37793.62206377939, 38820.83537493876, 38867.0, 38943.831685049496, 38969.41545876814, 29906.40187196255, 31563.68436315636, 34047.319053618914, 38806.0, 38927.0, 7815.726449574252, 8018.478798878084, 8336.916630833704, 8561.315094792402, 8568.228859402652, 8467.957660211721, 8480.957595212047, 8521.616527256258, 8549.615267312955, 8570.185832345916, 8512.744617661449, 8575.742727718178, 8581.828363432727, 8582.742517724479, 8596.914030859705, 8509.361797865155, 8552.059273479912, 8552.486850788973, 8560.357973152039, 8566.828663426697, 8353.582320883932, 8421.0, 8422.578871056423, 8436.409451338408, 8437.240648341618, 30597.847010765025, 36767.0, 37107.62892371069, 37144.25711485755, 37152.81423592865, 3497.1257185703575, 3498.5801703795705, 3530.5410296661526, 3543.5216245806746, 3545.6099829018785, 30256.24359391011, 30340.938067167765, 30344.089677309716, 30384.696153038407, 30456.8477157615, 3125.3905488429764, 3151.16494129056, 3165.6201255849214, 3166.0976621662944, 3178.55500229968, 231134.0, 234143.0, 251658.0, 265065.0, 265188.0, 46515.76742116268, 46734.29898551524, 46736.532634673895, 46846.53153468422, 46917.530824691996, 48719.0, 48837.02325953461, 48902.0, 49205.50794492009, 49615.75192124016, 14413.711725765426, 14472.927635361966, 14610.853891461162, 14613.488527901685, 14616.488422905148, 11640.0, 11764.117691173196, 11779.411029448493, 11800.232984855957, 11810.40947952599, 13847.861521384657, 13928.955328350212, 13929.23389213603, 13944.721105577832, 14039.649008774863, 6203.844903877439, 6250.531210159256, 6253.499720022387, 6262.1546091278005, 6275.6862156891975, 1004.3070281505737, 1006.9043440873105, 1040.1834559870617, 1054.583439541377, 1070.1652710885521, 17264.568385790455, 20276.695849562268, 23093.884530577576, 24186.911588978186, 24511.632325515133, 972.9810642915978, 1079.8056349857102, 1115.2583531951325, 1124.4771181400688, 1144.2390810111183, 12231.798276310172, 12356.814647780291, 12401.813972790234, 12411.75176496465, 12413.75172496545, 315010.0, 317521.0, 317924.0, 318165.0, 318806.0, 46697.76651116791, 50184.0, 53390.73304633452, 53448.19827702588, 53588.196177057376, 1468.5594321703659, 1506.3296832909468, 1527.7250094982787, 1535.9539213823602, 1555.922203889801, 23354.766452335938, 23615.52768944646, 23626.409339766644, 23767.64348534808, 23782.286531404086, 32058.83970580132, 36307.63692363042, 36331.0, 36378.4543231857, 36428.90713278606, 268751.0, 269641.94654694764, 270560.0, 271575.0, 271924.0, 33652.0, 34905.47641785324, 35099.47350789689, 35138.12154696051, 35161.0, 43652.0, 44134.0, 44279.77860110679, 44318.77840610905, 44333.556664432945, 27974.86012569924, 28417.147485575464, 29989.550156748104, 30234.697653023188, 30598.84700576483, 29831.955881544043, 29946.70053299439, 30450.0, 30543.389132216787, 30566.08301750951, 35454.46818297779, 36847.0, 36867.26265474729, 37035.25929481449, 37048.07379815418, 23708.762912370654, 28710.42579148447, 30354.241143971576, 30484.695153048186, 30520.389592207586, 23847.880760596086, 24042.87978560096, 24121.879390602935, 24368.26895193147, 24573.877130614233], "value": 17260.18495830747, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.514521992729, 419.245358354961, 426.3647165723074, 441.856396671082, 450.8345925781854, 19744.308949186783, 21619.0, 22359.0, 22408.327750167522, 22454.663180052292, 22692.205772797955, 22737.20419785308, 22819.77180228197, 22827.77172228277, 22836.8858155709, 5960.761569537224, 6260.593061451013, 6286.245650521943, 6289.0, 6314.116023756677, 6016.969915150418, 6132.41742034506, 6180.227471566054, 6222.502199824025, 6253.968730156343, 6078.300995385543, 6114.96942515289, 6136.693165341738, 6138.355472675372, 6142.9249881270825, 5774.797882074107, 6046.0, 6088.269407671063, 6111.449969502743, 6121.66330851803, 5375.569954403638, 5396.163594642837, 5406.91889621656, 5417.702026388547, 5418.91871621926, 27465.0, 27858.721412785817, 28051.29871753202, 28093.438131237464, 28266.770647430087, 2123.787621237879, 2203.3610253026604, 2210.35899589119, 2211.5024119572968, 2219.422950032987, 13936.581902542839, 17591.85652932568, 17725.0, 18268.451946441626, 18368.816311836978, 2130.6910497977706, 2150.215171462405, 2154.2567814104264, 2171.1206961180746, 2181.9236326728487, 134871.65128348788, 147679.0, 151202.0, 151763.0, 152939.2353038239, 12613.684657883443, 12782.424790884432, 12834.422450989729, 12861.807072893915, 12954.80567791484, 16488.83511164873, 16525.0, 16594.253258603392, 16611.584710382336, 16623.91688041552, 8897.644094236159, 9098.818023639491, 9099.954500227457, 9111.362204645611, 9200.6779762708, 6794.320567943166, 7653.655585498666, 8507.447015943944, 8605.483670979762, 8795.692150774692, 8016.198380161937, 8177.264046235868, 8193.95903020493, 8236.752897413087, 8251.061000664839, 1913.0817207740085, 2198.736151661779, 2199.549092436047, 2199.626063569197, 2329.103295231348, 841.6338892581747, 886.7118186589385, 929.256594724218, 959.69289827256, 960.4285450157146, 14878.70242595142, 21132.47168820761, 21536.353909382742, 21610.35168944934, 21846.0, 916.7478943290648, 987.5457289646699, 997.5610731278215, 1034.9016843400016, 1044.9373037617768, 10565.471726413649, 10688.03807657299, 11039.448027598748, 11060.115190784718, 11105.167112466597, 504665.0, 506631.0, 508751.0, 520242.0, 528058.0, 106738.9326106729, 133710.0, 136423.0, 137254.0, 138924.61075389318, 1190.5356910804794, 1226.0865655087018, 1255.1339575692743, 1255.7300180461245, 1256.447163248168, 22305.88470576432, 22502.437439064153, 22513.662295065915, 22554.774452255268, 22567.0, 57746.711266443395, 58423.0, 58624.706876465345, 58696.706516467144, 59008.114878277716, 69628.3037169642, 75654.0, 75736.62131689527, 76186.61906690653, 77099.61450192938, 8809.603567839591, 8836.955815220883, 8854.601542930712, 9041.141091596288, 9110.043445438134, 12264.386780660752, 13818.447262109403, 13904.860951390356, 14097.365618547396, 14315.99788014829, 9653.951730241304, 11605.535778569101, 12069.818952715892, 12100.213486123726, 12193.207441516273, 10825.67522974312, 10991.230613856953, 11037.779244415227, 11367.374794386387, 11407.543698251977, 10633.361998280128, 11945.820812687643, 11952.282863028246, 12043.458044387848, 12060.276383417024, 8598.871016934876, 8675.652973880975, 8700.260477859452, 8727.519986400555, 8821.117888211127, 11728.94439500449, 12012.819807702717, 12072.517099315928, 12121.575744848888, 12125.211861228992], "value": 10440.679680220312, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.166638350606, 1422.2106730764428, 1427.643089227693, 1431.3916585451186, 1437.9287430864006, 11202.271852329613, 11471.541138354456, 11873.7625247495, 12041.698957526056, 12070.215435996653, 11446.942765286183, 11623.360715160663, 11709.824352634718, 11725.70685732857, 11728.941355293233, 2501.1746123779153, 2542.8092893033004, 2548.0062775517563, 2557.8465292082533, 2572.0869091472537, 2490.0537795637633, 2532.4428625702317, 2534.1890595009604, 2534.708508521516, 2538.7842033427182, 2468.518638865422, 2501.312139161727, 2507.5110353481077, 2508.836925599839, 2513.434477242615, 2453.2027091195255, 2468.6420469031978, 2485.2171565956664, 2485.9005639774387, 2486.9378265543414, 2442.3405680466353, 2469.5307891500556, 2480.503899220163, 2485.005997600956, 2545.9108931187507, 9753.90246097537, 11177.273477223955, 11507.942460287644, 11544.884551154464, 11577.247478913843, 5410.783568657288, 5680.0, 6461.903071453932, 6549.214094308713, 6555.967220163869, 3709.5733990591157, 3861.362875125601, 4180.832766689329, 4274.081072569413, 4276.144771045772, 1057.656261714938, 1097.8572785537829, 1238.1889862140313, 1244.570623135026, 1271.5676669932175, 15114.0, 15543.300551475211, 15863.127527985956, 16193.352265909349, 16227.188640567925, 5039.420466646309, 5798.971005144947, 5893.233879595625, 5992.131140984533, 6067.9975608049235, 13544.458221671022, 13630.659233519242, 13648.0, 13676.042677012514, 13687.384067716977, 6515.837104072436, 6602.537822352389, 6619.470442364599, 6630.436412904957, 6658.4673226141795, 5677.80127695537, 5944.346121926671, 6072.423119803611, 6086.726097325631, 6106.664133472702, 4317.697761156752, 4326.956730432718, 4664.180969421928, 4712.293156026623, 4778.546038126373, 1424.266502751084, 1471.7865909443285, 1471.9116852988855, 1491.4779827060659, 1497.2064805653022, 775.8874963130314, 796.243568609823, 807.9338908840963, 811.679386642273, 816.9264766170954, 6134.325224225332, 6162.907556386658, 6180.412860778309, 6235.937640623626, 6282.717277722513, 570.1704020649944, 583.9649621022668, 601.6059481039925, 606.3814908793095, 611.0, 3909.615134767789, 4233.97883010583, 4361.912761744811, 4397.912041759211, 4401.2297847877135, 14125.434982600786, 14152.858471415155, 14377.065490743069, 14815.777763333559, 14836.406543738129, 14227.644308892153, 14439.422423102958, 14692.0, 14710.55868323952, 14718.852811471748, 8305.211004954519, 8328.375371847136, 8366.414350995494, 8444.831103377897, 8472.19514146155, 5329.706866122246, 5805.767769289181, 7033.714042935964, 7075.929240707733, 7097.964510177416, 12560.995120390164, 12670.049746268882, 13226.206427614374, 13226.867731322563, 13384.93307533456, 11617.941910290732, 13403.396847142098, 13544.187348759106, 13615.931920340336, 13820.861791381958, 8717.564121794012, 9009.369344145847, 9012.188902998761, 9014.729558113268, 9034.503102329434, 10136.949315253376, 10268.127209187303, 10281.691549253534, 10286.022827831344, 10306.072453479223, 8473.025602055843, 8745.56272186401, 8756.168164024408, 8799.208071273617, 8891.021987581491, 7448.73929412468, 8480.279176270085, 8504.787380315542, 8524.37522348938, 8662.480251184948, 7646.541207527566, 7731.188225236269, 7734.1105772836845, 7758.9612051939375, 7764.767056988299, 5747.885042299215, 5793.681347525927, 5965.701714914324, 5977.850553736017, 6011.549133815069, 5005.174146265826, 5661.886762264814, 5751.309842818889, 5935.525157987437, 6074.331823499323], "value": 5256.626162184823, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.3397970541404, 384.3638777822704, 395.6834532374101, 396.4845700589235, 405.1208876737479, 5683.403242659523, 6013.887430825295, 6021.668808215546, 6208.751649933997, 6222.782202622903, 5244.5542128919, 5935.465808077272, 6075.726592303346, 6087.634741915488, 6088.756449742005, 3876.2635099331037, 4433.733975961452, 4442.0, 4448.354988526669, 4461.665375096864, 3831.8275677594575, 4278.315469524877, 4446.465969240607, 4490.663200259977, 4525.479569849476, 4437.914180335238, 4438.667099967499, 4445.555444455545, 4454.710443821157, 4457.375967364555, 4014.8356976476784, 4411.713238639494, 4430.534793846632, 4436.268015777393, 4450.688451808374, 3174.2699179188685, 3195.888143914975, 3678.0253232893037, 3716.5725941516803, 3770.6425686753023, 5941.257342832146, 5975.671338076404, 5993.370696076893, 5994.28068631767, 6010.75956961721, 4734.076855013273, 5299.205119232108, 5303.071962406602, 5303.151495760699, 5319.441458646825, 5022.253969998353, 5314.415414304423, 5846.7953621623465, 6409.359064093554, 6469.417752402306, 4006.699497537667, 4277.866365413168, 4314.9784251078545, 4317.201317756225, 4351.390805287262, 5393.892122157574, 6213.533984951102, 6601.768938087192, 6840.589564626089, 7002.614856182908, 4226.36604509326, 4333.653307735437, 4348.239058164809, 4359.237133501624, 4373.781310934441, 4235.618794308527, 4238.978805106017, 4422.624076953431, 4444.1111777643955, 4476.820927162942, 5089.389273287229, 5230.712310822942, 5255.868603284948, 5256.763445644955, 5261.973690131525, 6422.775202867877, 6509.349065093453, 6515.902261465987, 6525.249596296487, 6540.9345906541275, 1359.9252041137831, 1428.0788891165348, 1496.9625759355886, 1503.0, 1515.8711509521813, 953.1977934153292, 990.346371394876, 998.9051040151174, 1002.3234316836107, 1007.8185926533295, 5837.270341207349, 6061.727037322212, 6100.206973093558, 6125.908111378333, 6151.96924015377, 6355.682215889187, 6763.458923286125, 6779.423748981293, 6782.152230971129, 6807.591544507345, 4895.290182923456, 6047.848803779941, 6116.052011938156, 6124.173236613089, 6127.0, 3021.3655132422, 3540.592831824322, 3564.269324788412, 3573.446115852047, 3577.799653213255, 6717.5297729159465, 6878.312168783082, 6881.243063263037, 6969.815131427669, 6976.93023069773, 5641.464060914206, 6037.305709843425, 6439.3238709934785, 6723.294054124344, 6997.860042799116, 5869.918292896264, 6499.707513161919, 6881.311868813079, 7020.789376318617, 7024.014793712651, 6594.65889609201, 6785.728570857111, 6825.931740682729, 6833.384995350442, 6877.4841886857685, 6635.104260924906, 6684.4318232950745, 6717.865642687217, 6746.831329216809, 6751.426128779109, 6824.556403833735, 6865.313468653295, 6896.344847239504, 6927.515073944775, 6963.79108626742, 4138.6482149017675, 4305.418768466342, 4369.825206991684, 4372.125574884972, 4385.824567017284, 6114.48026917717, 6339.683015849281, 6807.128039789098, 6822.488313376417, 6822.658867056726, 6343.397377249154, 6550.934490655032, 6596.769113081019, 6598.40614344692, 6613.5370524062855, 5322.813701520428, 5861.3845546217035, 6584.979328204136, 6589.7364105435245, 6603.438707709898, 5058.747062646927, 6399.880020996214, 6466.482681385572, 6468.708908099053, 6500.414962653384, 5719.399463056486, 5823.0, 6580.539362244788, 6608.471322294301, 6636.568623039487, 5414.918776218281, 5673.872218004628, 5881.852953676193, 6336.922723136986, 6429.903551446826], "value": 4703.499709550422, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.2344684974477, 821.6713314674133, 832.3881946769127, 855.6962278391171, 865.1996902864851, 14728.18994955277, 15141.54575362738, 16527.75208371874, 17027.91486042571, 17075.48773536793, 16014.83985160148, 17341.826581734178, 17580.208890599923, 17629.29482820689, 17659.91170044148, 985.7092157813462, 1020.8162530744463, 1025.646152077532, 1039.974000649986, 1044.650042235848, 1032.9431881246528, 1036.7356324137334, 1037.9325343852624, 1039.0907955538903, 1040.1470793948965, 956.2636769687336, 1059.0838924330437, 1064.834950582657, 1069.6256310291415, 1070.4326706845345, 1049.228816819637, 1053.310081896363, 1055.8046761349176, 1058.0688993685585, 1058.9682309530726, 771.1748429180778, 782.2568559868091, 783.2637320918318, 784.2862994674819, 787.3897729259812, 17094.91452542729, 17200.009951981614, 17294.0, 17319.393821216196, 17533.123343832758, 17564.12179391038, 18031.459056228334, 18124.18441170137, 18127.36554220609, 18165.182566784395, 14248.572542823731, 14651.194184319858, 14681.486147984877, 14696.779548306784, 14807.185604791732, 3624.4200927851416, 3634.4184930411006, 3637.8362973665985, 3667.156553992597, 3671.0638787109383, 151057.48942510545, 152751.0, 154665.0, 155479.0, 157188.0, 13523.526676566273, 13581.981351398486, 13582.388792504164, 13735.0, 13793.448262069605, 26125.477490450085, 26309.736902630728, 26530.867345663533, 26845.060422885104, 27031.1890643281, 6787.321267873173, 7017.47368947331, 7759.650815713307, 7826.569538675428, 7856.960715196502, 3976.224636195914, 4082.775447350424, 4086.7547947123267, 4088.2845502037026, 4095.0990782027907, 3674.7243956703355, 4145.66834653227, 4192.559781222928, 4195.0, 4245.957540424618, 890.7060669978891, 903.2593273515654, 907.5144797533288, 908.1145882764253, 908.2007833106956, 634.2420807135521, 640.766120366063, 674.2381109346505, 681.465049935798, 681.8329509270161, 9703.66037188695, 9887.505624718735, 11185.42735170899, 11721.88278117225, 11975.0, 650.8600650860088, 660.9504287178481, 667.9398854103154, 680.6562685843717, 681.7750142452978, 5836.620619659709, 6282.528810339151, 6317.957537006434, 6435.0, 6480.708368123352, 129688.0, 135928.0, 136457.0, 137934.0, 139906.0, 80750.59624701839, 99840.00159998452, 102222.0, 108434.45782771036, 118060.50667213385, 74690.0, 86777.0, 92035.079649204, 94427.0, 95661.0433895666, 10357.4303413313, 11639.185257032264, 12692.682682933, 12928.806067909178, 12941.352932353533, 35259.647403526666, 40453.39319910145, 41286.79356603198, 41378.793106034274, 42016.0, 40822.59177408188, 47261.0, 53103.937921240584, 53558.0, 53750.0, 14688.632784180481, 16464.506064818073, 17016.23426945766, 17080.57298567546, 17092.230849612046, 15647.687046258783, 18593.53516162107, 18739.156737947098, 19045.04774761284, 19201.32795352156, 14896.851031489547, 17492.737608935622, 17943.910280449036, 17977.460676179737, 18166.90916545409, 16864.915675422035, 16878.57803554921, 17052.829471705125, 17484.0, 17604.38384656531, 17048.0, 17210.741838872178, 17318.740218896473, 17338.393156239472, 17564.297428103244, 10421.426821524889, 10621.415822129857, 11896.702582435508, 12126.27242365461, 12258.632241032783, 11749.0, 13284.734305314032, 13376.933115334361, 13417.597472075853, 13446.462141514621], "value": 8608.173307687735, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 115.0, 114.0, 113.0, 113.0, 112.0, 115.0, 116.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 145.0, 151.0, 109.0, 121.0, 98.0, 95.0, 142.0, 143.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 196.0, 210.0, 218.0, 214.0, 211.0, 217.0, 202.0, 196.0], "value": 206.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 56.0, 83.0, 62.0, 74.0, 65.0, 83.0, 79.0, 60.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 69.0, 84.0, 92.0, 102.0, 77.0, 84.0, 69.0, 72.0, 90.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 90.0, 92.0, 91.0, 91.0, 91.0, 103.0, 90.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 105.0, 98.0, 98.0, 107.0, 96.0, 97.0, 112.0, 151.0, 135.0], "value": 109.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 91.0, 94.0, 93.0, 98.0, 91.0, 91.0, 92.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 52.0, 52.0, 53.0, 52.0, 52.0, 51.0, 52.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 61.0, 71.0, 85.0, 58.0, 58.0, 56.0, 61.0, 61.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 90.0, 89.0, 87.0, 78.0, 112.0, 146.0, 87.0], "value": 94.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 169.0, 130.0, 137.0, 172.0, 154.0, 142.0, 142.0, 137.0, 139.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 189.0, 187.0, 190.0, 238.0, 185.0, 191.0, 188.0, 176.0, 194.0], "value": 193.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 91.0, 96.0, 94.0, 71.0, 64.0, 100.0, 93.0, 92.0, 71.0], "value": 84.7, "unit": "score"}]}]} 23:36:13 INFO - Return code: 0 23:36:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:36:13 INFO - # TBPL SUCCESS # 23:36:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:36:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:36:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:36:13 INFO - 2017-05-25 23:36:13.484 screenresolution[1668:11017] starting screenresolution argv=screenresolution get 23:36:13 INFO - 2017-05-25 23:36:13.487 screenresolution[1668:11017] Display 0: 1600x1200x32@60 23:36:13 INFO - 2017-05-25 23:36:13.493 screenresolution[1669:11020] starting screenresolution argv=screenresolution list 23:36:13 INFO - Available Modes on Display 0 23:36:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:36:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:36:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:36:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:36:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:36:13 INFO - Intel Iris: 23:36:13 INFO - Chipset Model: Intel Iris 23:36:13 INFO - Type: GPU 23:36:13 INFO - Bus: Built-In 23:36:13 INFO - VRAM (Dynamic, Max): 1536 MB 23:36:13 INFO - Vendor: Intel (0x8086) 23:36:13 INFO - Device ID: 0x0a2e 23:36:13 INFO - Revision ID: 0x0009 23:36:13 INFO - Displays: 23:36:13 INFO - Display: 23:36:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:36:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:36:13 INFO - Display Serial Number: 1600x1200 60 23:36:13 INFO - Main Display: Yes 23:36:13 INFO - Mirror: Off 23:36:13 INFO - Online: Yes 23:36:13 INFO - Rotation: Supported 23:36:13 INFO - Return code: 0 23:36:13 INFO - Running post-action listener: _resource_record_post_action 23:36:13 INFO - [mozharness: 2017-05-26 06:36:13.730161Z] Finished run-tests step (success) 23:36:13 INFO - Running post-run listener: _resource_record_post_run 23:36:13 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 39857152; Write bytes: 567873536; Read time: 280; Write time: 1766 23:36:13 INFO - TinderboxPrint: CPU usage
53.4% 23:36:13 INFO - TinderboxPrint: I/O read bytes / time
39,857,152 / 280 23:36:13 INFO - TinderboxPrint: I/O write bytes / time
567,873,536 / 1,766 23:36:13 INFO - TinderboxPrint: CPU idle
2,335.9 (46.6%) 23:36:13 INFO - TinderboxPrint: CPU system
1,018.1 (20.3%) 23:36:13 INFO - TinderboxPrint: CPU user
1,663.7 (33.2%) 23:36:13 INFO - TinderboxPrint: Swap in / out
282,497,024 / 0 23:36:13 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229024256; Write bytes: 231118848; Read time: 16721; Write time: 489 23:36:13 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 38812672; Write bytes: 318294016; Read time: 265; Write time: 1227 23:36:14 INFO - Running post-run listener: _upload_blobber_files 23:36:14 INFO - Blob upload gear active. 23:36:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:36:14 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 23:36:14 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'] 23:36:14 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 23:36:14 INFO - (blobuploader) - INFO - Open directory for files ... 23:36:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:36:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:36:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:16 INFO - (blobuploader) - INFO - Done attempting. 23:36:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:36:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:36:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:17 INFO - (blobuploader) - INFO - Done attempting. 23:36:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:36:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:36:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:18 INFO - (blobuploader) - INFO - Done attempting. 23:36:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:36:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:36:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:19 INFO - (blobuploader) - INFO - Done attempting. 23:36:19 INFO - (blobuploader) - INFO - Iteration through files over. 23:36:19 INFO - Return code: 0 23:36:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:36:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:36:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04fa3c4cdc3d46afa01fcf7fa33e6990c762b45fa6a2ebe18b0bbad6d171a534bdaef2af49cb77862ec23586ca9a44cfa7a3052574156b689710da9d9c54a607", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1620eb60e5c4943e6699c2fd6cc356aba591e2ab82c7f373b31847e00169ff813d830125dba40cb3c49af8c0a0122c280458d424ac28c244e728bd6076b00247", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d8d406d6f048ba812d39a1dce02784ebe63c819e1836c310341f08c6bbb8315920fc511106a0dea02af416c1fb8d45f5d2d43dff489d44995e5ab9553ceccd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/354e066462e7e5dc6af1134580a279d76e6ad0ec5d193fbeb18fb54d27025569488552d0958dafe5344505ec3087015c7a8000b6101de0eb29d9792c4b157fb6"} 23:36:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:36:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:36:19 INFO - Contents: 23:36:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04fa3c4cdc3d46afa01fcf7fa33e6990c762b45fa6a2ebe18b0bbad6d171a534bdaef2af49cb77862ec23586ca9a44cfa7a3052574156b689710da9d9c54a607", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1620eb60e5c4943e6699c2fd6cc356aba591e2ab82c7f373b31847e00169ff813d830125dba40cb3c49af8c0a0122c280458d424ac28c244e728bd6076b00247", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d8d406d6f048ba812d39a1dce02784ebe63c819e1836c310341f08c6bbb8315920fc511106a0dea02af416c1fb8d45f5d2d43dff489d44995e5ab9553ceccd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/354e066462e7e5dc6af1134580a279d76e6ad0ec5d193fbeb18fb54d27025569488552d0958dafe5344505ec3087015c7a8000b6101de0eb29d9792c4b157fb6"} 23:36:19 INFO - Running post-run listener: copy_logs_to_upload_dir 23:36:19 INFO - Copying logs to upload dir... 23:36:19 INFO - mkdir: /builds/slave/test/build/upload/logs 23:36:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.727136 ========= master_lag: -0.22 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-25 23:36:19.741563) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 23:36:19.745247) ========= 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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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/04fa3c4cdc3d46afa01fcf7fa33e6990c762b45fa6a2ebe18b0bbad6d171a534bdaef2af49cb77862ec23586ca9a44cfa7a3052574156b689710da9d9c54a607", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1620eb60e5c4943e6699c2fd6cc356aba591e2ab82c7f373b31847e00169ff813d830125dba40cb3c49af8c0a0122c280458d424ac28c244e728bd6076b00247", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d8d406d6f048ba812d39a1dce02784ebe63c819e1836c310341f08c6bbb8315920fc511106a0dea02af416c1fb8d45f5d2d43dff489d44995e5ab9553ceccd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/354e066462e7e5dc6af1134580a279d76e6ad0ec5d193fbeb18fb54d27025569488552d0958dafe5344505ec3087015c7a8000b6101de0eb29d9792c4b157fb6"} build_url:https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://queue.taskcluster.net/v1/task/DVs7eI4NRGiZL2MqYt4-yw/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/04fa3c4cdc3d46afa01fcf7fa33e6990c762b45fa6a2ebe18b0bbad6d171a534bdaef2af49cb77862ec23586ca9a44cfa7a3052574156b689710da9d9c54a607", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1620eb60e5c4943e6699c2fd6cc356aba591e2ab82c7f373b31847e00169ff813d830125dba40cb3c49af8c0a0122c280458d424ac28c244e728bd6076b00247", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d8d406d6f048ba812d39a1dce02784ebe63c819e1836c310341f08c6bbb8315920fc511106a0dea02af416c1fb8d45f5d2d43dff489d44995e5ab9553ceccd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/354e066462e7e5dc6af1134580a279d76e6ad0ec5d193fbeb18fb54d27025569488552d0958dafe5344505ec3087015c7a8000b6101de0eb29d9792c4b157fb6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 23:36:19.780392) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 23:36:19.780790) ========= 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.Y7gYE1KVLs/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.MSr1OA3UTQ/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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.005106 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 23:36:19.829785) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 23:36:19.830135) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 23:36:19.830444) ========= ========= Total master_lag: -0.08 =========