builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0161 starttime: 1497252936.03 results: success (0) revision: 27cad9749cddf68e11fdd4e5d73dad84a8f8cf23 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:36.033963) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:36.039355) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:36.039670) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:36.073651) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:36.073932) ========= 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.pubefrYdV1/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.xf3pA1gg0Z/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-12 00:35:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2017-06-12 00:35:36 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.713072 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:36.810841) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:36.811152) ========= 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.pubefrYdV1/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.xf3pA1gg0Z/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.030123 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:36.861615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-12 00:35:36.861941) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 27cad9749cddf68e11fdd4e5d73dad84a8f8cf23 --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 27cad9749cddf68e11fdd4e5d73dad84a8f8cf23 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pubefrYdV1/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.xf3pA1gg0Z/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-12 00:35:36,959 truncating revision to first 12 chars 2017-06-12 00:35:36,959 Setting DEBUG logging. 2017-06-12 00:35:36,959 attempt 1/10 2017-06-12 00:35:36,959 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/27cad9749cdd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 00:35:39,229 unpacking tar archive at: mozilla-central-27cad9749cdd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.548479 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-12 00:35:39.431595) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:39.431916) ========= script_repo_revision: 27cad9749cddf68e11fdd4e5d73dad84a8f8cf23 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:39.432315) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:39.432577) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 00:35:39.451017) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-12 00:35:39.451366) ========= /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.pubefrYdV1/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.xf3pA1gg0Z/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:35:39 INFO - MultiFileLogger online at 20170612 00:35:39 in /builds/slave/test 00:35:39 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 00:35:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:35:39 INFO - {'append_to_log': False, 00:35:39 INFO - 'base_work_dir': '/builds/slave/test', 00:35:39 INFO - 'blob_upload_branch': 'Firefox', 00:35:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:35:39 INFO - 'branch': 'Firefox', 00:35:39 INFO - 'buildbot_json_path': 'buildprops.json', 00:35:39 INFO - 'config_files': ('talos/mac_config.py',), 00:35:39 INFO - 'default_actions': ('clobber', 00:35:39 INFO - 'read-buildbot-config', 00:35:39 INFO - 'download-and-extract', 00:35:39 INFO - 'populate-webroot', 00:35:39 INFO - 'create-virtualenv', 00:35:39 INFO - 'install', 00:35:39 INFO - 'setup-mitmproxy', 00:35:39 INFO - 'run-tests'), 00:35:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:35:39 INFO - 'download_minidump_stackwalk': True, 00:35:39 INFO - 'download_symbols': 'ondemand', 00:35:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:35:39 INFO - 'tooltool.py': '/tools/tooltool.py', 00:35:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:35:39 INFO - '/tools/misc-python/virtualenv.py')}, 00:35:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:35:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:35:39 INFO - 'gecko_profile': False, 00:35:39 INFO - 'gecko_profile_interval': 0, 00:35:39 INFO - 'installer_path': 'installer.exe', 00:35:39 INFO - 'log_level': 'info', 00:35:39 INFO - 'log_name': 'talos', 00:35:39 INFO - 'log_to_console': True, 00:35:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:35:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:35:39 INFO - 'opt_config_files': (), 00:35:39 INFO - 'pip_index': False, 00:35:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:35:39 INFO - 'cmd': ('bash', 00:35:39 INFO - '-c', 00:35:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:35:39 INFO - 'enabled': True, 00:35:39 INFO - 'halt_on_failure': False, 00:35:39 INFO - 'name': 'check_screen_resolution'},), 00:35:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:35:39 INFO - 'cmd': ('bash', 00:35:39 INFO - '-c', 00:35:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:35:39 INFO - 'enabled': True, 00:35:39 INFO - 'halt_on_failure': False, 00:35:39 INFO - 'name': 'check_screen_resolution'},), 00:35:39 INFO - 'run_cmd_checks_enabled': True, 00:35:39 INFO - 'suite': 'dromaeojs-e10s', 00:35:39 INFO - 'system_bits': '32', 00:35:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:35:39 INFO - 'title': 't-yosemite-r7-0161', 00:35:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:35:39 INFO - 'use_talos_json': True, 00:35:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:35:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:35:39 INFO - 'work_dir': 'build'} 00:35:39 INFO - [mozharness: 2017-06-12 07:35:39.663784Z] Running clobber step. 00:35:39 INFO - Running pre-action listener: _resource_record_pre_action 00:35:39 INFO - Running main action method: clobber 00:35:39 INFO - rmtree: /builds/slave/test/build 00:35:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:35:44 INFO - Running post-action listener: _resource_record_post_action 00:35:44 INFO - [mozharness: 2017-06-12 07:35:44.549994Z] Finished clobber step (success) 00:35:44 INFO - [mozharness: 2017-06-12 07:35:44.550174Z] Running read-buildbot-config step. 00:35:44 INFO - Running pre-action listener: _resource_record_pre_action 00:35:44 INFO - Running main action method: read_buildbot_config 00:35:44 INFO - Using buildbot properties: 00:35:44 INFO - { 00:35:44 INFO - "project": "", 00:35:44 INFO - "product": "firefox", 00:35:44 INFO - "who": "cbook@mozilla.com", 00:35:44 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 00:35:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 00:35:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 00:35:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 00:35:44 INFO - "stage_platform": "macosx64", 00:35:44 INFO - "basedir": "/builds/slave/test", 00:35:44 INFO - "buildnumber": 44, 00:35:44 INFO - "platform": "yosemite_r7", 00:35:44 INFO - "parent_task_id": "Ov8fZBRbRyeROZXMG0K31Q", 00:35:44 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 00:35:44 INFO - "slavebuilddir": "test", 00:35:44 INFO - "taskId": "d81fkS31R_-WjoZo3Y-SSQ", 00:35:44 INFO - "branch": "mozilla-central", 00:35:44 INFO - "script_repo_revision": "production", 00:35:44 INFO - "revision": "27cad9749cddf68e11fdd4e5d73dad84a8f8cf23", 00:35:44 INFO - "slavename": "t-yosemite-r7-0161", 00:35:44 INFO - "repo_path": "mozilla-central" 00:35:44 INFO - } 00:35:44 INFO - Finding installer, test and symbols from parent task. 00:35:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d81fkS31R_-WjoZo3Y-SSQ'}, attempt #1 00:35:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d81fkS31R_-WjoZo3Y-SSQ'}, attempt #1 00:35:45 INFO - Task dependencies: C1XspYbwTo2umMu9YmmzhQ Ov8fZBRbRyeROZXMG0K31Q 00:35:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q'}, attempt #1 00:35:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 00:35:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/test_packages.json 00:35:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/target.crashreporter-symbols.zip 00:35:46 INFO - Running post-action listener: _resource_record_post_action 00:35:46 INFO - [mozharness: 2017-06-12 07:35:46.324822Z] Finished read-buildbot-config step (success) 00:35:46 INFO - [mozharness: 2017-06-12 07:35:46.324934Z] Running download-and-extract step. 00:35:46 INFO - Running pre-action listener: _resource_record_pre_action 00:35:46 INFO - Running main action method: download_and_extract 00:35:46 INFO - mkdir: /builds/slave/test/build/tests 00:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:35:46 INFO - trying https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 00:35:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/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 00:35:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/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 00:35:47 INFO - Downloaded 1328 bytes. 00:35:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 00:35:47 INFO - Using the following test package requirements: 00:35:47 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 00:35:47 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 00:35:47 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 00:35:47 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 00:35:47 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'jsshell-mac.zip'], 00:35:47 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 00:35:47 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 00:35:47 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 00:35:47 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 00:35:47 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 00:35:47 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:35:47 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 00:35:47 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:35:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 00:35:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:35:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 00:35:49 INFO - Content-Length response header: 59540360 00:35:49 INFO - Bytes received: 59540360 00:36:07 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 00:36:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 00:36:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:36:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 00:36:09 INFO - Content-Length response header: 59540360 00:36:09 INFO - Bytes received: 59540360 00:36:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 00:36:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 00:36:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 00:36:28 INFO - Content-Length response header: 14450010 00:36:28 INFO - Bytes received: 14450010 00:36:28 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')]} 00:36:28 INFO - trying https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 00:36:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 00:36:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/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 00:36:30 INFO - Downloaded 69322740 bytes. 00:36:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 00:36:30 INFO - mkdir: /builds/slave/test/properties 00:36:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:36:30 INFO - Writing to file /builds/slave/test/properties/build_url 00:36:30 INFO - Contents: 00:36:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 00:36:30 INFO - Running post-action listener: _resource_record_post_action 00:36:30 INFO - Running post-action listener: set_extra_try_arguments 00:36:30 INFO - [mozharness: 2017-06-12 07:36:30.694615Z] Finished download-and-extract step (success) 00:36:30 INFO - [mozharness: 2017-06-12 07:36:30.694803Z] Running populate-webroot step. 00:36:30 INFO - Running pre-action listener: _resource_record_pre_action 00:36:30 INFO - Running main action method: populate_webroot 00:36:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:36:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:36:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:36:30 INFO - '--tppagecycles', 00:36:30 INFO - '7'], 00:36:30 INFO - 'tests': ['tsvgm']}}, 00:36:30 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:36:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:36:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:36:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 00:36:30 INFO - 'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:36:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 00:36:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:36:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 00:36:30 INFO - 'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['damp', 'tps']}, 00:36:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 00:36:30 INFO - 'tests': ['damp', 'tps']}, 00:36:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['dromaeo_dom']}, 00:36:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:36:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 00:36:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 00:36:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 00:36:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 00:36:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['a11yr', 00:36:30 INFO - 'ts_paint', 00:36:30 INFO - 'tpaint', 00:36:30 INFO - 'sessionrestore', 00:36:30 INFO - 'sessionrestore_no_auto_restore', 00:36:30 INFO - 'tabpaint']}, 00:36:30 INFO - 'other-e10s': {'tests': ['a11yr', 00:36:30 INFO - 'ts_paint', 00:36:30 INFO - 'tpaint', 00:36:30 INFO - 'sessionrestore', 00:36:30 INFO - 'sessionrestore_no_auto_restore', 00:36:30 INFO - 'tabpaint']}, 00:36:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:36:30 INFO - 'ts_paint', 00:36:30 INFO - 'tpaint', 00:36:30 INFO - 'sessionrestore', 00:36:30 INFO - 'sessionrestore_no_auto_restore', 00:36:30 INFO - 'tabpaint']}, 00:36:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:36:30 INFO - 'ts_paint', 00:36:30 INFO - 'tpaint', 00:36:30 INFO - 'sessionrestore', 00:36:30 INFO - 'sessionrestore_no_auto_restore', 00:36:30 INFO - 'tabpaint']}, 00:36:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['a11yr', 00:36:30 INFO - 'ts_paint', 00:36:30 INFO - 'tpaint', 00:36:30 INFO - 'sessionrestore', 00:36:30 INFO - 'sessionrestore_no_auto_restore', 00:36:30 INFO - 'tabpaint']}, 00:36:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['a11yr', 00:36:30 INFO - 'ts_paint', 00:36:30 INFO - 'tpaint', 00:36:30 INFO - 'sessionrestore', 00:36:30 INFO - 'sessionrestore_no_auto_restore', 00:36:30 INFO - 'tabpaint']}, 00:36:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 00:36:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 00:36:30 INFO - 'bloom_basic_ref']}, 00:36:30 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 00:36:30 INFO - 'talos_options': ['--mitmproxy', 00:36:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 00:36:30 INFO - '--firstNonBlankPaint'], 00:36:30 INFO - 'tests': ['quantum_pageload_google', 00:36:30 INFO - 'quantum_pageload_youtube', 00:36:30 INFO - 'quantum_pageload_amazon', 00:36:30 INFO - 'quantum_pageload_facebook']}, 00:36:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['tsvgx', 00:36:30 INFO - 'tsvgr_opacity', 00:36:30 INFO - 'tart', 00:36:30 INFO - 'tscrollx', 00:36:30 INFO - 'cart', 00:36:30 INFO - 'tsvg_static']}, 00:36:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:36:30 INFO - 'tsvgr_opacity', 00:36:30 INFO - 'tart', 00:36:30 INFO - 'tscrollx', 00:36:30 INFO - 'cart', 00:36:30 INFO - 'tsvg_static']}, 00:36:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 00:36:30 INFO - 'talos_options': ['--disable-e10s'], 00:36:30 INFO - 'tests': ['tp5o']}, 00:36:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 00:36:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 00:36:30 INFO - 'talos_options': ['--disable-e10s', 00:36:30 INFO - '--xperf_path', 00:36:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:36:30 INFO - 'tests': ['tp5n']}, 00:36:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 00:36:30 INFO - 'talos_options': ['--xperf_path', 00:36:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:36:30 INFO - 'tests': ['tp5n']}}, 00:36:30 INFO - 'talos.zip': {'path': '', 00:36:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:36:30 INFO - Running post-action listener: _resource_record_post_action 00:36:30 INFO - [mozharness: 2017-06-12 07:36:30.717172Z] Finished populate-webroot step (success) 00:36:30 INFO - [mozharness: 2017-06-12 07:36:30.717286Z] Running create-virtualenv step. 00:36:30 INFO - Running pre-action listener: _resource_record_pre_action 00:36:30 INFO - Running main action method: create_virtualenv 00:36:30 INFO - Creating virtualenv /builds/slave/test/build/venv 00:36:30 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 00:36:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:36:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 00:36:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:36:30 INFO - Using real prefix '/tools/python27' 00:36:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:36:31 INFO - Installing distribute.............................................................................................................................................................................................done. 00:36:34 INFO - Installing pip.................done. 00:36:34 INFO - Return code: 0 00:36:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:36:34 INFO - Reading from file tmpfile_stdout 00:36:34 INFO - Output received: 00:36:34 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) 00:36:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:36:34 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')]} 00:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:34 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')]} 00:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:34 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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:34 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 00:36:34 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 00:36:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:34 INFO - 'HOME': '/Users/cltbld', 00:36:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:34 INFO - 'LOGNAME': 'cltbld', 00:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:34 INFO - 'MOZ_NO_REMOTE': '1', 00:36:34 INFO - 'NO_EM_RESTART': '1', 00:36:34 INFO - 'PAGER': '/bin/cat', 00:36:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:34 INFO - 'PWD': '/builds/slave/test', 00:36:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:34 INFO - 'SHELL': '/bin/bash', 00:36:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:34 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:34 INFO - 'USER': 'cltbld', 00:36:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:34 INFO - 'XPC_FLAGS': '0x0', 00:36:34 INFO - 'XPC_SERVICE_NAME': '0', 00:36:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:34 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 00:36:34 INFO - Cleaning up... 00:36:34 INFO - Return code: 0 00:36:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:36:34 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')]} 00:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:34 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')]} 00:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:35 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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:35 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 00:36:35 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 00:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:35 INFO - 'HOME': '/Users/cltbld', 00:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:35 INFO - 'LOGNAME': 'cltbld', 00:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:35 INFO - 'MOZ_NO_REMOTE': '1', 00:36:35 INFO - 'NO_EM_RESTART': '1', 00:36:35 INFO - 'PAGER': '/bin/cat', 00:36:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:35 INFO - 'PWD': '/builds/slave/test', 00:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:35 INFO - 'SHELL': '/bin/bash', 00:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:35 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:35 INFO - 'USER': 'cltbld', 00:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:35 INFO - 'XPC_FLAGS': '0x0', 00:36:35 INFO - 'XPC_SERVICE_NAME': '0', 00:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:35 INFO - Downloading/unpacking psutil>=3.1.1 00:36:35 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 00:36:35 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 00:36:35 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 00:36:35 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 00:36:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:36:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:36:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:36:37 INFO - Installing collected packages: psutil 00:36:37 INFO - Running setup.py install for psutil 00:36:37 INFO - building 'psutil._psutil_osx' extension 00:36:37 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 00:36:38 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 00:36:38 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 00:36:38 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 00:36:38 INFO - building 'psutil._psutil_posix' extension 00:36:38 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 00:36:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:36:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:36:38 INFO - ^ 00:36:38 INFO - 1 warning generated. 00:36:38 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 00:36:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:36:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:36:38 INFO - Successfully installed psutil 00:36:38 INFO - Cleaning up... 00:36:38 INFO - Return code: 0 00:36:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:36:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:36:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:38 INFO - 'HOME': '/Users/cltbld', 00:36:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:38 INFO - 'LOGNAME': 'cltbld', 00:36:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:38 INFO - 'MOZ_NO_REMOTE': '1', 00:36:38 INFO - 'NO_EM_RESTART': '1', 00:36:38 INFO - 'PAGER': '/bin/cat', 00:36:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:38 INFO - 'PWD': '/builds/slave/test', 00:36:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:38 INFO - 'SHELL': '/bin/bash', 00:36:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:38 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:38 INFO - 'USER': 'cltbld', 00:36:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:38 INFO - 'XPC_FLAGS': '0x0', 00:36:38 INFO - 'XPC_SERVICE_NAME': '0', 00:36:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:36:39 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 00:36:39 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 00:36:39 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 00:36:39 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 00:36:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:36:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:36:40 INFO - Installing collected packages: mozsystemmonitor 00:36:40 INFO - Running setup.py install for mozsystemmonitor 00:36:40 INFO - Successfully installed mozsystemmonitor 00:36:40 INFO - Cleaning up... 00:36:40 INFO - Return code: 0 00:36:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:36: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')]} 00:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36: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')]} 00:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 00:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 00:36:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:40 INFO - 'HOME': '/Users/cltbld', 00:36:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:40 INFO - 'LOGNAME': 'cltbld', 00:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:40 INFO - 'MOZ_NO_REMOTE': '1', 00:36:40 INFO - 'NO_EM_RESTART': '1', 00:36:40 INFO - 'PAGER': '/bin/cat', 00:36:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:40 INFO - 'PWD': '/builds/slave/test', 00:36:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:40 INFO - 'SHELL': '/bin/bash', 00:36:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:40 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:40 INFO - 'USER': 'cltbld', 00:36:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:40 INFO - 'XPC_FLAGS': '0x0', 00:36:40 INFO - 'XPC_SERVICE_NAME': '0', 00:36:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:40 INFO - Downloading/unpacking jsonschema==2.5.1 00:36:40 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 00:36:40 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 00:36:40 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 00:36:40 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 00:36:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:36:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:36:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:36:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:36:42 INFO - Downloading functools32-3.2.3-2.tar.gz 00:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:36:42 INFO - warning: no files found matching '*.txt' 00:36:42 INFO - no previously-included directories found matching 'build' 00:36:42 INFO - no previously-included directories found matching 'dist' 00:36:42 INFO - no previously-included directories found matching '.git*' 00:36:42 INFO - Installing collected packages: jsonschema, functools32 00:36:42 INFO - Running setup.py install for functools32 00:36:42 INFO - warning: no files found matching '*.txt' 00:36:42 INFO - no previously-included directories found matching 'build' 00:36:42 INFO - no previously-included directories found matching 'dist' 00:36:42 INFO - no previously-included directories found matching '.git*' 00:36:42 INFO - Successfully installed jsonschema functools32 00:36:42 INFO - Cleaning up... 00:36:42 INFO - Return code: 0 00:36:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 00:36:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 00:36:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:42 INFO - 'HOME': '/Users/cltbld', 00:36:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:42 INFO - 'LOGNAME': 'cltbld', 00:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:42 INFO - 'MOZ_NO_REMOTE': '1', 00:36:42 INFO - 'NO_EM_RESTART': '1', 00:36:42 INFO - 'PAGER': '/bin/cat', 00:36:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:42 INFO - 'PWD': '/builds/slave/test', 00:36:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:42 INFO - 'SHELL': '/bin/bash', 00:36:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:42 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:42 INFO - 'USER': 'cltbld', 00:36:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:42 INFO - 'XPC_FLAGS': '0x0', 00:36:42 INFO - 'XPC_SERVICE_NAME': '0', 00:36:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:36:43 INFO - Cleaning up... 00:36:43 INFO - Return code: 0 00:36:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:36:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:43 INFO - 'HOME': '/Users/cltbld', 00:36:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:43 INFO - 'LOGNAME': 'cltbld', 00:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:43 INFO - 'MOZ_NO_REMOTE': '1', 00:36:43 INFO - 'NO_EM_RESTART': '1', 00:36:43 INFO - 'PAGER': '/bin/cat', 00:36:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:43 INFO - 'PWD': '/builds/slave/test', 00:36:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:43 INFO - 'SHELL': '/bin/bash', 00:36:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:43 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:43 INFO - 'USER': 'cltbld', 00:36:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:43 INFO - 'XPC_FLAGS': '0x0', 00:36:43 INFO - 'XPC_SERVICE_NAME': '0', 00:36:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:43 INFO - Downloading/unpacking blobuploader==1.2.4 00:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:36:44 INFO - Downloading blobuploader-1.2.4.tar.gz 00:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:36:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:36:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:36:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:36:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:36:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:36:45 INFO - Downloading docopt-0.6.1.tar.gz 00:36:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:36:45 INFO - Installing collected packages: blobuploader, requests, docopt 00:36:45 INFO - Running setup.py install for blobuploader 00:36:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:36:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:36:45 INFO - Running setup.py install for requests 00:36:46 INFO - Running setup.py install for docopt 00:36:46 INFO - Successfully installed blobuploader requests docopt 00:36:46 INFO - Cleaning up... 00:36:46 INFO - Return code: 0 00:36:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:36:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:46 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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:46 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 00:36:46 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 00:36:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:46 INFO - 'HOME': '/Users/cltbld', 00:36:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:46 INFO - 'LOGNAME': 'cltbld', 00:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:46 INFO - 'MOZ_NO_REMOTE': '1', 00:36:46 INFO - 'NO_EM_RESTART': '1', 00:36:46 INFO - 'PAGER': '/bin/cat', 00:36:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:46 INFO - 'PWD': '/builds/slave/test', 00:36:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:46 INFO - 'SHELL': '/bin/bash', 00:36:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:46 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:46 INFO - 'USER': 'cltbld', 00:36:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:46 INFO - 'XPC_FLAGS': '0x0', 00:36:46 INFO - 'XPC_SERVICE_NAME': '0', 00:36:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:36:46 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-F5XkJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-SvLkq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-YWrovK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Irdvao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-LTNCee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-5wbwuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ziXIpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-37Nlkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-14sqsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-M4POXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:36:47 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-7xJcRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:36:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-skuKtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:36:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-I5E_nV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:36:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-8jV8BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:36:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-vfGUtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:36:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-p2gEZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:36:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-rrjeaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:36:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:36:48 INFO - Running setup.py install for manifestparser 00:36:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:36:48 INFO - Running setup.py install for mozcrash 00:36:48 INFO - Running setup.py install for mozdebug 00:36:49 INFO - Running setup.py install for mozdevice 00:36:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:36:49 INFO - Running setup.py install for mozfile 00:36:49 INFO - Running setup.py install for mozhttpd 00:36:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:36:49 INFO - Running setup.py install for mozinfo 00:36:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:36:49 INFO - Running setup.py install for mozInstall 00:36:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:36:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:36:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:36:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:36:49 INFO - Running setup.py install for mozleak 00:36:49 INFO - Running setup.py install for mozlog 00:36:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:36:50 INFO - Running setup.py install for moznetwork 00:36:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:36:50 INFO - Running setup.py install for mozprocess 00:36:50 INFO - Running setup.py install for mozprofile 00:36:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:36:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:36:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:36:50 INFO - Running setup.py install for mozrunner 00:36:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:36:50 INFO - Running setup.py install for mozscreenshot 00:36:50 INFO - Running setup.py install for moztest 00:36:51 INFO - Running setup.py install for mozversion 00:36:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:36:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:36:51 INFO - Cleaning up... 00:36:51 INFO - Return code: 0 00:36:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:36:51 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')]} 00:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:51 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')]} 00:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:51 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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:51 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 00:36:51 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 00:36:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:51 INFO - 'HOME': '/Users/cltbld', 00:36:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:51 INFO - 'LOGNAME': 'cltbld', 00:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:51 INFO - 'MOZ_NO_REMOTE': '1', 00:36:51 INFO - 'NO_EM_RESTART': '1', 00:36:51 INFO - 'PAGER': '/bin/cat', 00:36:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:51 INFO - 'PWD': '/builds/slave/test', 00:36:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:51 INFO - 'SHELL': '/bin/bash', 00:36:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:51 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:51 INFO - 'USER': 'cltbld', 00:36:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:51 INFO - 'XPC_FLAGS': '0x0', 00:36:51 INFO - 'XPC_SERVICE_NAME': '0', 00:36:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:36:51 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-j0klAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:36:51 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)) 00:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:36:51 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-zgacJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:36:51 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)) 00:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:36:51 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-CdEHuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:36:51 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)) 00:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:36:51 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-v6QumV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:36:51 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)) 00:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:36:51 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-yAZ9xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:36:52 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-GuMiu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:36:52 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-hBrX2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:36:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-n2wNet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:36:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-CO6KsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:36:52 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-wWoInz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:36:52 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-lZMEbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:36:52 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-75mZez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:36:52 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-k7WP9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:36:52 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)) 00:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:36:52 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-w0lZ1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:36:53 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)) 00:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:36:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-5ItmGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:36:53 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)) 00:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:36:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-DXB3QK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:36:53 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)) 00:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:36:53 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-rBun_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:36:53 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)) 00:36:53 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)) 00:36:53 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)) 00:36:53 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)) 00:36:53 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)) 00:36:53 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)) 00:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 00:36:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:36:53 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 00:36:53 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 00:36:53 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 00:36:53 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 00:36:54 INFO - Downloading blessings-1.6.tar.gz 00:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:36:54 INFO - Installing collected packages: blessings 00:36:54 INFO - Running setup.py install for blessings 00:36:55 INFO - Successfully installed blessings 00:36:55 INFO - Cleaning up... 00:36:55 INFO - Return code: 0 00:36:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:36:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:36:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:36:55 INFO - Reading from file tmpfile_stdout 00:36:55 INFO - Current package versions: 00:36:55 INFO - blessings == 1.6 00:36:55 INFO - blobuploader == 1.2.4 00:36:55 INFO - docopt == 0.6.1 00:36:55 INFO - functools32 == 3.2.3-2 00:36:55 INFO - jsonschema == 2.5.1 00:36:55 INFO - manifestparser == 1.1 00:36:55 INFO - mozInstall == 1.13 00:36:55 INFO - mozcrash == 1.0 00:36:55 INFO - mozdebug == 0.1 00:36:55 INFO - mozdevice == 0.51 00:36:55 INFO - mozfile == 1.2 00:36:55 INFO - mozhttpd == 0.7 00:36:55 INFO - mozinfo == 0.10 00:36:55 INFO - mozleak == 0.1 00:36:55 INFO - mozlog == 3.4 00:36:55 INFO - moznetwork == 0.27 00:36:55 INFO - mozprocess == 0.25 00:36:55 INFO - mozprofile == 0.28 00:36:55 INFO - mozrunner == 6.13 00:36:55 INFO - mozscreenshot == 0.1 00:36:55 INFO - mozsystemmonitor == 0.3 00:36:55 INFO - moztest == 0.8 00:36:55 INFO - mozversion == 1.4 00:36:55 INFO - psutil == 3.1.1 00:36:55 INFO - requests == 1.2.3 00:36:55 INFO - wsgiref == 0.1.2 00:36:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:36:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:55 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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:55 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 00:36:55 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 00:36:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:55 INFO - 'HOME': '/Users/cltbld', 00:36:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:55 INFO - 'LOGNAME': 'cltbld', 00:36:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:55 INFO - 'MOZ_NO_REMOTE': '1', 00:36:55 INFO - 'NO_EM_RESTART': '1', 00:36:55 INFO - 'PAGER': '/bin/cat', 00:36:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:55 INFO - 'PWD': '/builds/slave/test', 00:36:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:55 INFO - 'SHELL': '/bin/bash', 00:36:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:55 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:55 INFO - 'USER': 'cltbld', 00:36:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:55 INFO - 'XPC_FLAGS': '0x0', 00:36:55 INFO - 'XPC_SERVICE_NAME': '0', 00:36:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:55 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)) 00:36:55 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)) 00:36:55 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)) 00:36:55 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)) 00:36:55 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)) 00:36:55 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)) 00:36:55 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)) 00:36:55 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)) 00:36:55 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)) 00:36:55 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 00:36: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 00:36: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 00:36: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 00:36: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 00:36:57 INFO - Downloading marionette_driver-1.4.0.tar.gz 00:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 00:36:57 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)) 00:36:57 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)) 00:36:57 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)) 00:36:57 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)) 00:36:57 INFO - Installing collected packages: marionette-driver 00:36:57 INFO - Running setup.py install for marionette-driver 00:36:57 INFO - Successfully installed marionette-driver 00:36:57 INFO - Cleaning up... 00:36:57 INFO - Return code: 0 00:36:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:36:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:36:57 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 0x107eb1c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107eb5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89fb553f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, '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.pubefrYdV1/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.xf3pA1gg0Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 00:36:57 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 00:36:57 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 00:36:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:36:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:36:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:36:57 INFO - 'HOME': '/Users/cltbld', 00:36:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:36:57 INFO - 'LOGNAME': 'cltbld', 00:36:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:36:57 INFO - 'MOZ_NO_REMOTE': '1', 00:36:57 INFO - 'NO_EM_RESTART': '1', 00:36:57 INFO - 'PAGER': '/bin/cat', 00:36:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:36:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:36:57 INFO - 'PWD': '/builds/slave/test', 00:36:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:36:57 INFO - 'SHELL': '/bin/bash', 00:36:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:36:57 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:36:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:36:57 INFO - 'USER': 'cltbld', 00:36:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:36:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:36:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:36:57 INFO - 'XPC_FLAGS': '0x0', 00:36:57 INFO - 'XPC_SERVICE_NAME': '0', 00:36:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:36:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:36:57 INFO - Cleaning up... 00:36:57 INFO - Return code: 0 00:36:57 INFO - Running post-action listener: _resource_record_post_action 00:36:57 INFO - Running post-action listener: _start_resource_monitoring 00:36:57 INFO - Starting resource monitoring. 00:36:57 INFO - [mozharness: 2017-06-12 07:36:57.681890Z] Finished create-virtualenv step (success) 00:36:57 INFO - [mozharness: 2017-06-12 07:36:57.682878Z] Running install step. 00:36:57 INFO - Running pre-action listener: _resource_record_pre_action 00:36:57 INFO - Running main action method: install 00:36:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:36:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:36:57 INFO - Reading from file tmpfile_stdout 00:36:57 INFO - Detecting whether we're running mozinstall >=1.0... 00:36:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:36:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:36:58 INFO - Reading from file tmpfile_stdout 00:36:58 INFO - Output received: 00:36:58 INFO - Usage: mozinstall [options] installer 00:36:58 INFO - Options: 00:36:58 INFO - -h, --help show this help message and exit 00:36:58 INFO - -d DEST, --destination=DEST 00:36:58 INFO - Directory to install application into. [default: 00:36:58 INFO - "/builds/slave/test"] 00:36:58 INFO - --app=APP Application being installed. [default: firefox] 00:36:58 INFO - mkdir: /builds/slave/test/build/application 00:36:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 00:36:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 00:37:19 INFO - Reading from file tmpfile_stdout 00:37:19 INFO - Output received: 00:37:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:37:19 INFO - Running post-action listener: _resource_record_post_action 00:37:19 INFO - [mozharness: 2017-06-12 07:37:19.927358Z] Finished install step (success) 00:37:19 INFO - [mozharness: 2017-06-12 07:37:19.927489Z] Running setup-mitmproxy step. 00:37:19 INFO - Running pre-action listener: _resource_record_pre_action 00:37:19 INFO - Running main action method: setup_mitmproxy 00:37:19 INFO - Skipping: mitmproxy is not required 00:37:19 INFO - Running post-action listener: _resource_record_post_action 00:37:19 INFO - [mozharness: 2017-06-12 07:37:19.928200Z] Finished setup-mitmproxy step (success) 00:37:19 INFO - [mozharness: 2017-06-12 07:37:19.928314Z] Running run-tests step. 00:37:19 INFO - Running pre-action listener: _resource_record_pre_action 00:37:19 INFO - Running main action method: run_tests 00:37:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:37:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:37:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:37:19 INFO - 2017-06-12 00:37:19.942 screenresolution[1633:8599] starting screenresolution argv=screenresolution get 00:37:19 INFO - 2017-06-12 00:37:19.945 screenresolution[1633:8599] Display 0: 1600x1200x32@60 00:37:19 INFO - 2017-06-12 00:37:19.952 screenresolution[1634:8601] starting screenresolution argv=screenresolution list 00:37:19 INFO - Available Modes on Display 0 00:37:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:37:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:37:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:37:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:37:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:37:20 INFO - Intel Iris: 00:37:20 INFO - Chipset Model: Intel Iris 00:37:20 INFO - Type: GPU 00:37:20 INFO - Bus: Built-In 00:37:20 INFO - VRAM (Dynamic, Max): 1536 MB 00:37:20 INFO - Vendor: Intel (0x8086) 00:37:20 INFO - Device ID: 0x0a2e 00:37:20 INFO - Revision ID: 0x0009 00:37:20 INFO - Displays: 00:37:20 INFO - Display: 00:37:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:37:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:37:20 INFO - Display Serial Number: 1600x1200 60 00:37:20 INFO - Main Display: Yes 00:37:20 INFO - Mirror: Off 00:37:20 INFO - Online: Yes 00:37:20 INFO - Rotation: Supported 00:37:20 INFO - Return code: 0 00:37:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:37:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:37:20 INFO - Python 2.7.3 00:37:20 INFO - Return code: 0 00:37:20 INFO - grabbing minidump binary from tooltool 00:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:37:20 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 0x107c07d30>, '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 0x107bd1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bf8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:37:20 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 00:37:20 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 00:37:20 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 00:37:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:37:20 INFO - Return code: 0 00:37:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:37:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:37:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:37:20 INFO - ENV: RUST_BACKTRACE is now 1 00:37:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:37:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:37:20 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/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 00:37:20 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/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0161 --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 00:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pubefrYdV1/Render', 00:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:20 INFO - 'HOME': '/Users/cltbld', 00:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:20 INFO - 'LOGNAME': 'cltbld', 00:37:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:37:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:20 INFO - 'MOZ_NO_REMOTE': '1', 00:37:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:37:20 INFO - 'NO_EM_RESTART': '1', 00:37:20 INFO - 'PAGER': '/bin/cat', 00:37:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:20 INFO - 'PWD': '/builds/slave/test', 00:37:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:20 INFO - 'RUST_BACKTRACE': '1', 00:37:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 00:37:20 INFO - 'SHELL': '/bin/bash', 00:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xf3pA1gg0Z/Listeners', 00:37:20 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 00:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:20 INFO - 'USER': 'cltbld', 00:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:20 INFO - 'XPC_FLAGS': '0x0', 00:37:20 INFO - 'XPC_SERVICE_NAME': '0', 00:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:20 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/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 00:37:20 INFO - mozversion application_buildid: 20170612000113 00:37:20 INFO - mozversion application_changeset: 27cad9749cddf68e11fdd4e5d73dad84a8f8cf23 00:37:20 INFO - mozversion application_display_name: Nightly 00:37:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:37:20 INFO - mozversion application_name: Firefox 00:37:20 INFO - mozversion application_remotingname: firefox 00:37:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 00:37:20 INFO - mozversion application_vendor: Mozilla 00:37:20 INFO - mozversion application_version: 55.0a1 00:37:20 INFO - mozversion platform_buildid: 20170612000113 00:37:20 INFO - mozversion platform_changeset: 27cad9749cddf68e11fdd4e5d73dad84a8f8cf23 00:37:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 00:37:20 INFO - mozversion platform_version: 55.0a1 00:37:20 INFO - using testdate: 1497253040 00:37:20 INFO - actual date: 1497253040 00:37:20 INFO - starting webserver on 'localhost:49223' 00:37:20 INFO - SUITE-START | Running 2 tests 00:37:20 INFO - TEST-START | dromaeo_css 00:37:20 INFO - operating with platform_type : mac_ 00:37:20 INFO - Initialising browser for dromaeo_css test... 00:37:20 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpsXZF14/profile 00:37:20 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground) 00:37:54 INFO - TEST-INFO | 1644: killed by SIGTERM 00:37:54 INFO - Browser initialized. 00:37:54 INFO - Running cycle 1/1 for dromaeo_css test... 00:37:54 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpsXZF14/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:37:56 INFO - PID 1652 | 2017-06-12 00:37:56.552 plugin-container[1653:9219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b4b, name = 'com.apple.tsm.portname' 00:37:56 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:37:56 INFO - PID 1652 | 2017-06-12 00:37:56.553 plugin-container[1653:9219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 00:37:56 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:37:56 INFO - PID 1652 | 2017-06-12 00:37:56.553 plugin-container[1653:9219] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:37:57 INFO - PID 1652 | 2017-06-12 00:37:57.339 plugin-container[1654:9264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9643, name = 'com.apple.tsm.portname' 00:37:57 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:37:57 INFO - PID 1652 | 2017-06-12 00:37:57.339 plugin-container[1654:9264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 00:37:57 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:37:57 INFO - PID 1652 | 2017-06-12 00:37:57.339 plugin-container[1654:9264] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:38:06 INFO - PID 1652 | RSS: Main: 253804544 00:38:06 INFO - PID 1652 | 00:41:08 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49223/tests/dromaeo/cssquery-ext.html) 00:41:11 INFO - PID 1652 | RSS: Main: 270700544 00:41:11 INFO - PID 1652 | 00:44:13 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-ext.html (next: http://localhost:49223/tests/dromaeo/cssquery-jquery.html) 00:44:13 INFO - PID 1652 | RSS: Main: 279097344 00:44:13 INFO - PID 1652 | 00:47:15 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49223/tests/dromaeo/cssquery-mootools.html) 00:47:16 INFO - PID 1652 | RSS: Main: 282476544 00:47:16 INFO - PID 1652 | 00:50:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49223/tests/dromaeo/cssquery-prototype.html) 00:50:18 INFO - PID 1652 | RSS: Main: 284745728 00:50:18 INFO - PID 1652 | 00:53:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49223/tests/dromaeo/cssquery-yui.html) 00:53:22 INFO - PID 1652 | RSS: Main: 287334400 00:53:22 INFO - PID 1652 | 00:56:23 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-yui.html (next: http://localhost:49223/tests/dromaeo/cssquery-dojo.html) 00:56:24 INFO - PID 1652 | RSS: Main: 287502336 00:56:24 INFO - PID 1652 | 00:56:24 INFO - PID 1652 | __start_tp_report 00:56:24 INFO - PID 1652 | _x_x_mozilla_page_load 00:56:24 INFO - PID 1652 | _x_x_mozilla_page_load_details 00:56:24 INFO - PID 1652 | |i|pagename|runs| 00:56:24 INFO - PID 1652 | |0;dojo.html;3445.638207988163;3471.149568355753;3571.4642803579477;3578.9463158052636;3590.6409359064082;6140.62695141751;6160.168377269068;6161.784337548187;6204.78283260086;6246.09431632413;6216.937830621692;6227.53293502988;6233.532485063627;6251.71867265973;6260.624362538239;5451.618386712941;5456.072467680504;5456.399796022434;5523.558115350783;5583.190437386576;5482.122860342346;5503.071910056459;5544.085225937715;5577.107662773957;5587.888242235153;4511.413516242867;5323.813666521653;5404.972975135139;5502.504774570288;5524.751386187631;4639.234526303156;5144.742762861842;5363.570914326883;5457.754401051962;5531.944680553223;4887.266909963499;4980.800767969313;4983.803887067078;5405.594580406485;5536.197251398565;6203.1005504202085;6247.750089996394;6271.749130034793;6275.811725648238;6288.276848162474;6484.675766211671;6702.296258892844;6725.899111513331;6740.865182696368;6764.729410823561;6637.3030831762935;6713.99290106483;6720;6798.320167983212;6799.898001529981;5847.649141051551;5913.817236552707;6264.060390941349;6267.843303917347;6340.3659634036685;6941.271166527543;6943.097397338414;6944.895826562605;6970.756023539152;6999.510034297652;6361.777337793155;6442.226932768098;6460.321666224979;6585.506087043491;6639.203295604461;6588.8023359299295;6593.86812263752;6595.835104122435;6598.670066496656;6600.471962243009;6535.8692826143215;6547.9672601636685;6599.274079851212;6603.80188594343;6613.041109039163;6705.497087718343;6725.024871393621;6727.730890764413;6732.596044237362;6751.122354093936;5315.3090098286975;5358.19627055937;5364.758585863646;5382.138857782736;5390.703511306916;4761.7142971421135;4773.71357718538;4776.47458779534;4792.4249090108715;4820.855374338776;6130.724117414727;6275.717592708339;6306.810795676137;6316.9368306317265;6358.936410635927;6675.232348279913;6688.023829395872;6757.831054223684;6773.661316934232;6792.93207067923;5010.924836127461;6324.841878953063;6327.240731112296;6355.650439225795;6362.300146983828;5973.342932277446;5978.372270911578;5979.4618484336615;5980.372060933626;6005.669688167105;6963.512554121163;6995.160580730447;6999.0551275577145;7019.824504387431;7031.753888613874;6536.248331441755;6638.435732962752;6825.726970921108;6836.897446538405;6880.380765730908;6783.4573234142135;6840.076589660531;6893.75871844483;6970.407515361251;6977.616231107338;6601.438877695257;6677.332266773284;6720.798376048726;6720.966395167993;6746.966265168643;6834;6835.521513494008;6854.6229957352825;6865.588064716133;6872.10662613857;6521.5545019908905;6552.541322107598;6556.770513032021;6559.639219842955;6569.540132190701;6290.087937248982;6371;6384.457321127757;6592.340765923369;6594.802155935329;5626.971865140812;5830.833833233455;6240.469560087444;6329.082283069021;6396.520260980352;6548.639824809681;6555.2133743951035;6571.967140164268;6580.967095164494;6621.801345959629;6370.331115232834;6389.361063893573;6390.201224846895;6397.976323788172;6398.0083087121575 00:56:24 INFO - PID 1652 | |1;ext.html;757.7272182014474;816.975490735278;817.6238930092157;822.6668199379252;864.5849992003838;28766.424671506556;37111.81444092779;40161.59838401611;40187;40271.395929061015;39553;39570.8021459893;39808;39965;40042.19915601686;7482.588457634828;7609.277118673717;7626.351760100398;7626.809329766745;7640.083190017206;7279.67241474135;8270.669173233064;8530.57347132644;8561.785955351104;8567.74296771098;8591.656333746643;8638.870416943751;8655.740327790176;8667.479951202948;8668.479891206516;8561.95719021407;8609.182127697859;8621;8630.223279904838;8646.65413383467;8306.127856575033;8314.293285070777;8317.334613230987;8363;8377;37975.24049519022;38230;38312.80843595792;38328.233435331415;38498.80750596257;3599;3622.3479773640734;3641.3263546243766;3645.38028535147;3650.4524321351746;30963.22591935197;31082.222944426343;31091.533626995613;31129.844350778327;31200;3076.446239676857;3121.750259979196;3143.2141964508874;3150.936981260362;3179.9682003179905;249946;252678;253557;313746;317598;48921.510784891696;49779;49793.004139917;49945.001099977795;50091;52139;52438.47561524412;52575.737121314145;52597.68505787386;52685;14143.151410915378;14237.572872813833;14308;14347.282635868165;14371.92814035923;11571.537138514364;11574;11591.826122608167;11593.304401735923;11595.768084638428;12020.63938081859;13846.515371962134;13847.584572462842;13911.652208694864;13996.650083747987;5350.9732451337495;5852.619579727304;5906.675132867647;6009.248843894513;6022.638641681426;986.3440811860177;991.7371896447447;991.7619771254847;1051.6897515233024;1056.5351245451977;23298.417539561648;23488.882555587454;23571;23674.526509469713;24579.508409831702;1089.9455027248605;1097.7475180708482;1149.706824759681;1154.318951818431;1156.4506859241792;11957.820632690517;12704.491820327268;12783.977281817432;12810.615681529569;12897.871021289668;260597;318561;319053.4047329748;319743;319772;50785.73035674138;56468.71765641228;56696;56805.71597141988;56811;1451.267110109409;1487.7768334749874;1538.7384144695204;1547.002183591577;1553.2932515705327;22320.441988949755;23031.539369212856;24483.265502034967;28573;30495.390092197587;52184.73907630437;52354.47645523496;52510;52584;52695.946081077396;256041;256623;257667;257676;258349;35140.06729629895;35714;35819.462708058876;36506.452403214505;36601.26797463983;42936.57063429326;43706.562934370246;44120.779396102815;44188.11623767571;45038.0992380144;28911.42177156487;30443.558459985245;30737.846310768302;31366.52950205703;31482.212944676565;29996.85001574978;30456.543151852296;31382;31396.529052064696;31467.84266078655;36934.44598331081;37834.0541486454;38630.22739545137;38635.6136438632;38915.61084389233;30755.077347679606;30859.537106943862;31882;32001.839990799897;32478;20699.336904358814;23152.652710209022;24949.376265593506;25146.37134071663;25470 00:56:24 INFO - PID 1652 | |2;jquery.html;461.7252734622899;481.63877092180866;497.9651424400291;511.7602704740594;528.4688887667893;21730;21878.671819922674;22381.44046398841;22406.88796556019;22473;23283.534329313447;23799.881000594974;24817.37956551087;24885.253442396755;24922.87538562309;6323.810285691425;6461;6463.644499552523;6479.384458476437;6484.546081774278;6428.485721142298;6458.741650334005;6470.191226096738;6479.902801457982;6483.027545868111;5797.130430435426;6309.77915772948;6327.905081423783;6359.55483116182;6388.584741991755;6343;6396.776112836028;6396.968015159941;6397;6409.198850143732;5837.066069428871;5845.561582881301;5856.736446859902;5860.267466566679;5862.941370586324;27820.86089569539;29988.8500557498;30257.69742302593;30499.08502744899;30639.846800766078;2259.6271615183473;2277.533105713325;2303.67748515208;2304.585174668559;2304.9078036878504;18822.435326940213;19319.323823666244;19368.225270989144;19395.030248487517;19424.028798560157;2185.726784151981;2211.3365990202874;2245.057076028072;2250.1561914282142;2266.7166604174477;161025;166101.16949415175;170954;171355.14322428431;189816;13703.21858158453;13833.584992450242;14733;15218.467353642569;15231.390744370101;20157.697634535492;20203.595928081355;20339.8983005087;20414.591708165754;20419.8979005104;9593.808123837483;9607.375520591138;9666;9725.708228753148;9814.509274536245;7142;7673.808154796175;8995.640174392951;9008.648702694518;9065.954670226738;7639.465237433326;9033.683821066232;9117.270618350516;9190.264778817678;9208.388532006871;2610.2691246451022;2612.333854867035;2623.3310505820896;2631.0265201875427;2768.806183567171;826.1985873702499;957.3968399908014;959.712086374085;984.3503287829998;1009.5658866687248;20067;20230.089645965967;20308.593828123354;21429.892850535958;21490.78509214919;939.1641439119189;973.4889183178747;984.3011461862015;1018.2312354172552;1031.2729525684374;10591.523381447852;10704.250702450909;10704.447855060973;10746.623868164734;11091.889081109248;538095;580972.0951395216;583770;587240;592936;152975;160820;169205;172067;175797;1040.505759764106;1096.550414330121;1100.6587957732968;1216.197309775544;1221.9266843989212;20645.58708825845;21126.788732112484;21330.89334553317;23239.419014524774;24048.51902961966;53819.73090134681;64034;67548.3245167542;67681.66159169172;69817.65091174512;83540.58229708813;92774;93805;95854;96331.51834240784;15872.679147401526;16027.919860400623;16602.25289861935;16786.992780433215;16869.240884159997;17737.024463654623;18147.637047258715;18370.908145459187;18794.53013674669;18976.051197439796;13040.739185216433;15227.771583426036;15534.1456219909;15551.533453996399;15672.05967641945;13111.40998655082;13474.66313342135;13541.79687304711;13755;13995.860041399455;15551.688966220838;15611.141387223812;15638.609034774221;15742.449014284444;16141.354345826036;16868.746968795233;16895.32418703238;16920.40778572744;17029.65940681155;17218.483445496655;14457.421703132177;15030.398784048515;15043.774343384639;15114.697706046038;15231.847681523042 00:56:24 INFO - PID 1652 | |3;mootools.html;1325.6420766393073;1451.5355086372365;1472.8158980127484;1473.0498828255775;1497.805285312909;12961.287129207889;13480;13535.255560944157;13544.05191636586;13606.251656158907;12968.481260749559;13417.127886687358;13433.261170635635;13434.059615826896;13449.193048417077;2695.4204845958116;2745.574435962429;2748.051922086877;2748.876300566469;2756.4073724149303;2244.9887750561224;2350.3771500552366;2664.840109593421;2722.3058120179326;2724.6730392352947;2298.3564601911494;2314.976850231493;2554.220962606404;2635.8813853376646;2648.3246772073094;2691.7039125696156;2693.8653067346654;2696.8516731579757;2698.406350602874;2700.891964321425;2519.8236123471365;2529.380301826046;2533.8226324157317;2541.7077036140895;2542.974570254311;10685.893141068645;13041.347932603427;13049.021323400782;13138.146020508637;13140.34298285082;7244.963775181091;7506.174320824705;7513.849723005509;7535.095788505359;7541.208173141851;3550.260372417523;3725.3666876631037;4630.981184139484;4634.050019745978;4652.767361631905;1354.356680576728;1364.9249291288975;1365.1467832604621;1387.8473367929519;1395.644110751752;19394.321198758116;19419.22323107074;19543.0228488575;19656.115474803668;19691.60616787656;5884.7940322088525;6436.903446448214;6451.387118223761;6454.483641308684;6612.900806487906;13058.738825223443;13969.79045314321;13992.950528710184;13995;14005.579832605039;7108.07595012645;7273.818154546178;7278.381337586365;7332.5967071811565;7347.485676002629;6548.1814773153355;6724.529282950244;6753.257141714407;6813.080234168423;6820.624865632438;4240.363945408213;4798.976005120022;4915.066137433876;4935.407751069894;4958;1437.741206582825;1441.077710265431;1447.1534152520683;1450.100937418786;1471.0952764050032;767.439768968656;818.9590520473953;837.6105111123235;852.2542775071812;854.6880388658095;6879.828004299932;6946.444284457231;7005.474589405815;7233.168185658611;7260.782176534713;937.2174234514121;946.6071580294239;982.9727934308664;984.2716389871443;1019.7807471393686;4221.957780422218;4256.446661933929;4264.701470897069;4307.956920430818;4538.795754191069;17162.742558861628;17798.733019004725;17832.93002419833;17838.821611783977;17857.821421785877;17685.292588296325;17697.292108315523;17704.645907081787;17710.645787084188;17757.644847103245;8489.151084891584;8762.211400973943;8977.551122443982;9115.726528204032;9202.861957070649;7783.688652454065;7879.606019698877;8055.718049868226;8057.677692892453;8368.83262334762;15360.615984600474;15487.380504779683;15945.202739863193;16152.596185095466;16157.75763363574;15475.613109671898;16152.353905843634;16180.919095404448;16345.26446309895;16554;10623.734406639587;10832;10868.967448092419;10870.184736144663;10871.891281087404;12101.213421127599;12156.209846359958;12217.328046957502;12243.571474998333;12253.571125010581;8962.73111806647;9575.760605984906;9650.131488166098;9759.268054895769;9963;9372.297077719335;9507.524623768923;9562.808743824946;9675.322727409013;9940.005999400291;8541;8894;8928.464292142493;9006.144416280444;9067.09329067088;5841.591088623755;6461.64460954652;6675.532712710064;6823.590584564942;6910.032595436744;6793.422559082534;6818.21590517077;6984.511084224056;7124.643767811693;7161.606111663908 00:56:24 INFO - PID 1652 | |4;prototype.html;426.1923654674394;426.3264042812357;435.01252157602244;439.8834308908139;464.14828789171867;6342.175517182765;6374.585651932621;6375.776847810327;6380.649064301461;6387.712552935118;5469.480399362054;5975.223220981277;6176.660283684396;6188.876222475559;6208.6585237811905;4706.9764651176865;4710.623150147997;4722.693024953384;4738.715677059387;4747.52524747526;4516.435445569304;4689.437267527885;4712.670113092085;4727.2036626082045;4770.85687429376;4462.040661257829;4481.663875209356;4665.626749860003;4688.9765551172195;4694.3662605548325;4537.684071619226;4635.351050852883;4679.134360143385;4680.742559159245;4689.9531004690025;3318.734501239895;3354.3962086824363;3766.0773110587807;4032.717709760318;4113.629773320416;5299.178627312773;5650.971745141289;5979.9402005980255;5995.880082398371;6012.789552365646;5515.806946756885;5517.310336207974;5530.695811730353;5531.308586426697;5540.639858409231;6591.4397276231575;6643.468522518187;6654.833629159261;6666.300038495985;6679.866402671968;3925.1168487090463;4190.497140343178;4654.2785868190485;4661.324108004321;4667.603253723438;6002.3697511761075;6244;6554.082428460019;7013.298670132997;7103.8934415983285;4144.958550414518;4543.4775000875325;4766.189747742892;4768.380110585602;4775.2359622460235;4497.9325310120375;4837.903241935212;4842.58837998774;4863.1732605457155;4874.098291816025;5140.974295128501;5524.640898341595;5538.141588053858;5539.861503462445;5581.358143813513;5581.911527252147;6135.447809697149;6138.478229350466;6544.70548825304;6693.364130407604;1397.1438828832495;1536.4622382166376;1542.8225754038428;1626.544567521096;1633.5262773795466;950.6102497975796;967.5936106835146;1109.545086514525;1124.1624989382963;1128.5203788389902;5888.617239879394;6306.2747784004505;6310.337414571404;6346.365363463617;6375.808725738235;5251.36983561975;6036.245469316335;6847.486438517131;6892.448604111659;6947.548409353376;6042.942485065096;6237.750489980441;6279.869623467728;6299.74005198962;6313.179286692701;3545.6986156177013;3554.489342029627;3567.340042092221;3569.428891377367;3585.874494392736;7024.929750702529;7035.542689725152;7044.577325360392;7048;7057.117860267466;6897.482553838147;7024.192217895007;7033.331833475811;7072.858542829114;7080.610566418896;5764.682942438206;7040.3663670269925;7063.505554611128;7072.524616384224;7112.253213412517;6162.722677479434;6343.873122537431;6469.223693156851;6629.204495460575;6873.381395674413;6394.6659469292445;6778.695528339441;6790.32096790317;6800.761973330909;6808.217055038734;5905.4685078343155;5954.970225148846;6534.281229064704;7022.964885175541;7059.18819335783;4690;4725.267583524559;4732.621390288838;4732.692374995614;4738.5833225054785;6854.760083397057;6871.381575658214;6874.8625027500175;6874.931250687429;6926.58440493572;5550.833474995757;6393.424591786761;6541.345865413611;6547.9672601636685;6646.60120392778;5796.62321949072;6485.416312531895;6628.5028622852515;6656.400923916871;6689.397954183952;5652;6227.657478838526;6527.51043671717;6548.214214294315;6736.966315168393;5479.561635069105;5881.852953676193;6284.214473190851;6669.566478178903;6697;5590.748416321194;6166.876662466815;6182.350853160533;6487.610743355413;6576.506761992965 00:56:24 INFO - PID 1652 | |5;yui.html;818.8567000774863;826.9131741167179;828.8508068547662;856.6444925355976;857.0058731871031;16591;16772.832271677296;16818.66362672746;16957.982521048747;17006.82993170068;11981;12642.683932901682;13107.016973726984;15797.684046319067;16507.83492165078;996.1731762637012;1007.028217769852;1015.7562185075602;1019.3985548526368;1020.0717347214037;994.6469003503761;1004.5278719002057;1013.5236438873754;1018.6638409324942;1022.7034160093564;1075.3977772447415;1079.1582565598806;1080.8108580998332;1082.8971247731454;1087.271528076187;899.7930475990526;987.3878195518753;1056.1603525197486;1067.4716015572342;1078.385320367391;781.2734157233771;785.9174786647434;787.2914377060634;788.1212448120361;789.6643926331286;16573.005619662857;17452.040137792417;17533.473995780147;17599.296028158857;17606.2077206526;17682.204300806494;18132.818671813246;18141.54646133844;18249.17878695462;18336.083195840154;14175.929120354436;14906.850931490655;14984.175870327128;15038.172900490468;15058.774118388234;3343.2477692519233;3523;3577.8568857245677;3603.5135256740527;3605.3149901518627;131458.34270828776;155896.66155007685;160432.19783901007;163362.1831890833;163711;13394.196348219139;13500.5274815381;13872.445102196001;13909;13914.721705565831;23449.296521104396;26278.6058209127;26754.464910701678;26776;26857;7209.71161153548;7967.2431119043595;8194.590270486451;8196.877046844302;8213.876791848126;4005.5994400560294;4043.4137050128156;4057.6753859691153;4078.388241763759;4083.85706500277;4990.251462280687;5183.637145399861;5221;5226.268322434862;5253.028189784902;950.3205208275978;978.1636700620867;1034.2243317511866;1054.146141625286;1055.5619417941623;645.9515536334794;647.0682217606632;654.1659384285075;678.0168755304828;679.0628932073805;10695.358278503314;12204.267743935392;12620.990320774315;12689.936550317188;12706.110572259853;624.1137584629837;648.5298158834902;652.0935899100255;673.1182151381705;680.0750978669006;4765.928511072337;5641.351244606841;5644.3508996465935;5867.618604790675;5918.408159184133;127230;129037.97382089378;129048;133889;139096;111334.81552638492;111769.32346014817;113951.4302428499;115600.42199789116;119056.80943190458;91134.54432727926;91173.54413228024;94404.52797735967;96168.51915740517;96677;10756.892431075588;10847.474156426822;10920.890791091988;10951.890481095086;11561;41335;43519.12961740684;43806.34290485582;43969.560304396546;44705.55294447144;55007;55415;56228.15657765055;57159.714201428724;57502.71248643898;15012.324445400202;15141.242937852843;15283.159426231665;15410.998285111433;15428.614284642525;15779.842201577838;16277.511674649779;16316.836831631532;16345.182740863147;16458.094804785374;14388.784168237275;14504.564863054125;15089.622759431102;15122.54632361031;15418.306176221873;16251.756223656419;17092.316307347566;17184.484465466056;17210.82789172092;17460.12699365052;15888.2055897207;16051.438199663422;16418.83581164173;16467.423640172536;16489.752653709966;10334.638287659896;11101.777964440827;11537.48081336325;11732.70668233301;11766.470508827295;12535.623931282076;12648.747025059263;12723.872761272269;12795.552155674506;13228.338583071001 00:56:24 INFO - PID 1652 | __end_tp_report 00:56:24 INFO - PID 1652 | __start_cc_report 00:56:24 INFO - PID 1652 | _x_x_mozilla_cycle_collect,467 00:56:24 INFO - PID 1652 | __end_cc_report 00:56:24 INFO - PID 1652 | __startTimestamp1497254184024__endTimestamp 00:56:24 INFO - PID 1652 | 00:56:24 INFO - PID 1652 | ------- Summary: start ------- 00:56:24 INFO - PID 1652 | Number of tests: 6 00:56:24 INFO - PID 1652 | 00:56:24 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6158.88 Median:6371.00 stddev:762.01 (12.0%) stddev-sans-first:734.01 00:56:24 INFO - PID 1652 | Values: 3445.6 3471.1 3571.5 3578.9 3590.6 6140.6 6160.2 6161.8 6204.8 6246.1 6216.9 6227.5 6233.5 6251.7 6260.6 5451.6 5456.1 5456.4 5523.6 5583.2 5482.1 5503.1 5544.1 5577.1 5587.9 4511.4 5323.8 5405.0 5502.5 5524.8 4639.2 5144.7 5363.6 5457.8 5531.9 4887.3 4980.8 4983.8 5405.6 5536.2 6203.1 6247.8 6271.7 6275.8 6288.3 6484.7 6702.3 6725.9 6740.9 6764.7 6637.3 6714.0 6720.0 6798.3 6799.9 5847.6 5913.8 6264.1 6267.8 6340.4 6941.3 6943.1 6944.9 6970.8 6999.5 6361.8 6442.2 6460.3 6585.5 6639.2 6588.8 6593.9 6595.8 6598.7 6600.5 6535.9 6548.0 6599.3 6603.8 6613.0 6705.5 6725.0 6727.7 6732.6 6751.1 5315.3 5358.2 5364.8 5382.1 5390.7 4761.7 4773.7 4776.5 4792.4 4820.9 6130.7 6275.7 6306.8 6316.9 6358.9 6675.2 6688.0 6757.8 6773.7 6792.9 5010.9 6324.8 6327.2 6355.7 6362.3 5973.3 5978.4 5979.5 5980.4 6005.7 6963.5 6995.2 6999.1 7019.8 7031.8 6536.2 6638.4 6825.7 6836.9 6880.4 6783.5 6840.1 6893.8 6970.4 6977.6 6601.4 6677.3 6720.8 6721.0 6747.0 6834.0 6835.5 6854.6 6865.6 6872.1 6521.6 6552.5 6556.8 6559.6 6569.5 6290.1 6371.0 6384.5 6592.3 6594.8 5627.0 5830.8 6240.5 6329.1 6396.5 6548.6 6555.2 6572.0 6581.0 6621.8 6370.3 6389.4 6390.2 6398.0 6398.0 00:56:24 INFO - PID 1652 | 00:56:24 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:44281.25 Median:19709.23 stddev:73933.69 (375.1%) stddev-sans-first:74068.87 00:56:24 INFO - PID 1652 | Values: 757.7 817.0 817.6 822.7 864.6 28766.4 37111.8 40161.6 40187.0 40271.4 39553.0 39570.8 39808.0 39965.0 40042.2 7482.6 7609.3 7626.4 7626.8 7640.1 7279.7 8270.7 8530.6 8561.8 8567.7 8591.7 8638.9 8655.7 8667.5 8668.5 8562.0 8609.2 8621.0 8630.2 8646.7 8306.1 8314.3 8317.3 8363.0 8377.0 37975.2 38230.0 38312.8 38328.2 38498.8 3599.0 3622.3 3641.3 3645.4 3650.5 30963.2 31082.2 31091.5 31129.8 31200.0 3076.4 3121.8 3143.2 3150.9 3180.0 249946.0 252678.0 253557.0 313746.0 317598.0 48921.5 49779.0 49793.0 49945.0 50091.0 52139.0 52438.5 52575.7 52597.7 52685.0 14143.2 14237.6 14308.0 14347.3 14371.9 11571.5 11574.0 11591.8 11593.3 11595.8 12020.6 13846.5 13847.6 13911.7 13996.7 5351.0 5852.6 5906.7 6009.2 6022.6 986.3 991.7 991.8 1051.7 1056.5 23298.4 23488.9 23571.0 23674.5 24579.5 1089.9 1097.7 1149.7 1154.3 1156.5 11957.8 12704.5 12784.0 12810.6 12897.9 260597.0 318561.0 319053.4 319743.0 319772.0 50785.7 56468.7 56696.0 56805.7 56811.0 1451.3 1487.8 1538.7 1547.0 1553.3 22320.4 23031.5 24483.3 28573.0 30495.4 52184.7 52354.5 52510.0 52584.0 52695.9 256041.0 256623.0 257667.0 257676.0 258349.0 35140.1 35714.0 35819.5 36506.5 36601.3 42936.6 43706.6 44120.8 44188.1 45038.1 28911.4 30443.6 30737.8 31366.5 31482.2 29996.9 30456.5 31382.0 31396.5 31467.8 36934.4 37834.1 38630.2 38635.6 38915.6 30755.1 30859.5 31882.0 32001.8 32478.0 20699.3 23152.7 24949.4 25146.4 25470.0 00:56:24 INFO - PID 1652 | 00:56:24 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:39884.15 Median:2263.17 stddev:99147.21 (4380.9%) stddev-sans-first:99381.16 00:56:24 INFO - PID 1652 | Values: 461.7 481.6 498.0 511.8 528.5 21730.0 21878.7 22381.4 22406.9 22473.0 23283.5 23799.9 24817.4 24885.3 24922.9 6323.8 6461.0 6463.6 6479.4 6484.5 6428.5 6458.7 6470.2 6479.9 6483.0 5797.1 6309.8 6327.9 6359.6 6388.6 6343.0 6396.8 6397.0 6397.0 6409.2 5837.1 5845.6 5856.7 5860.3 5862.9 27820.9 29988.9 30257.7 30499.1 30639.8 2259.6 2277.5 2303.7 2304.6 2304.9 18822.4 19319.3 19368.2 19395.0 19424.0 2185.7 2211.3 2245.1 2250.2 2266.7 161025.0 166101.2 170954.0 171355.1 189816.0 13703.2 13833.6 14733.0 15218.5 15231.4 20157.7 20203.6 20339.9 20414.6 20419.9 9593.8 9607.4 9666.0 9725.7 9814.5 7142.0 7673.8 8995.6 9008.6 9066.0 7639.5 9033.7 9117.3 9190.3 9208.4 2610.3 2612.3 2623.3 2631.0 2768.8 826.2 957.4 959.7 984.4 1009.6 20067.0 20230.1 20308.6 21429.9 21490.8 939.2 973.5 984.3 1018.2 1031.3 10591.5 10704.3 10704.4 10746.6 11091.9 538095.0 580972.1 583770.0 587240.0 592936.0 152975.0 160820.0 169205.0 172067.0 175797.0 1040.5 1096.6 1100.7 1216.2 1221.9 20645.6 21126.8 21330.9 23239.4 24048.5 53819.7 64034.0 67548.3 67681.7 69817.7 83540.6 92774.0 93805.0 95854.0 96331.5 15872.7 16027.9 16602.3 16787.0 16869.2 17737.0 18147.6 18370.9 18794.5 18976.1 13040.7 15227.8 15534.1 15551.5 15672.1 13111.4 13474.7 13541.8 13755.0 13995.9 15551.7 15611.1 15638.6 15742.4 16141.4 16868.7 16895.3 16920.4 17029.7 17218.5 14457.4 15030.4 15043.8 15114.7 15231.8 00:56:24 INFO - PID 1652 | 00:56:24 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:8026.00 Median:2723.49 stddev:5338.61 (196.0%) stddev-sans-first:5329.84 00:56:24 INFO - PID 1652 | Values: 1325.6 1451.5 1472.8 1473.0 1497.8 12961.3 13480.0 13535.3 13544.1 13606.3 12968.5 13417.1 13433.3 13434.1 13449.2 2695.4 2745.6 2748.1 2748.9 2756.4 2245.0 2350.4 2664.8 2722.3 2724.7 2298.4 2315.0 2554.2 2635.9 2648.3 2691.7 2693.9 2696.9 2698.4 2700.9 2519.8 2529.4 2533.8 2541.7 2543.0 10685.9 13041.3 13049.0 13138.1 13140.3 7245.0 7506.2 7513.8 7535.1 7541.2 3550.3 3725.4 4631.0 4634.1 4652.8 1354.4 1364.9 1365.1 1387.8 1395.6 19394.3 19419.2 19543.0 19656.1 19691.6 5884.8 6436.9 6451.4 6454.5 6612.9 13058.7 13969.8 13993.0 13995.0 14005.6 7108.1 7273.8 7278.4 7332.6 7347.5 6548.2 6724.5 6753.3 6813.1 6820.6 4240.4 4799.0 4915.1 4935.4 4958.0 1437.7 1441.1 1447.2 1450.1 1471.1 767.4 819.0 837.6 852.3 854.7 6879.8 6946.4 7005.5 7233.2 7260.8 937.2 946.6 983.0 984.3 1019.8 4222.0 4256.4 4264.7 4308.0 4538.8 17162.7 17798.7 17832.9 17838.8 17857.8 17685.3 17697.3 17704.6 17710.6 17757.6 8489.2 8762.2 8977.6 9115.7 9202.9 7783.7 7879.6 8055.7 8057.7 8368.8 15360.6 15487.4 15945.2 16152.6 16157.8 15475.6 16152.4 16180.9 16345.3 16554.0 10623.7 10832.0 10869.0 10870.2 10871.9 12101.2 12156.2 12217.3 12243.6 12253.6 8962.7 9575.8 9650.1 9759.3 9963.0 9372.3 9507.5 9562.8 9675.3 9940.0 8541.0 8894.0 8928.5 9006.1 9067.1 5841.6 6461.6 6675.5 6823.6 6910.0 6793.4 6818.2 6984.5 7124.6 7161.6 00:56:24 INFO - PID 1652 | 00:56:24 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5388.10 Median:5999.12 stddev:1647.11 (27.5%) stddev-sans-first:1609.08 00:56:24 INFO - PID 1652 | Values: 426.2 426.3 435.0 439.9 464.1 6342.2 6374.6 6375.8 6380.6 6387.7 5469.5 5975.2 6176.7 6188.9 6208.7 4707.0 4710.6 4722.7 4738.7 4747.5 4516.4 4689.4 4712.7 4727.2 4770.9 4462.0 4481.7 4665.6 4689.0 4694.4 4537.7 4635.4 4679.1 4680.7 4690.0 3318.7 3354.4 3766.1 4032.7 4113.6 5299.2 5651.0 5979.9 5995.9 6012.8 5515.8 5517.3 5530.7 5531.3 5540.6 6591.4 6643.5 6654.8 6666.3 6679.9 3925.1 4190.5 4654.3 4661.3 4667.6 6002.4 6244.0 6554.1 7013.3 7103.9 4145.0 4543.5 4766.2 4768.4 4775.2 4497.9 4837.9 4842.6 4863.2 4874.1 5141.0 5524.6 5538.1 5539.9 5581.4 5581.9 6135.4 6138.5 6544.7 6693.4 1397.1 1536.5 1542.8 1626.5 1633.5 950.6 967.6 1109.5 1124.2 1128.5 5888.6 6306.3 6310.3 6346.4 6375.8 5251.4 6036.2 6847.5 6892.4 6947.5 6042.9 6237.8 6279.9 6299.7 6313.2 3545.7 3554.5 3567.3 3569.4 3585.9 7024.9 7035.5 7044.6 7048.0 7057.1 6897.5 7024.2 7033.3 7072.9 7080.6 5764.7 7040.4 7063.5 7072.5 7112.3 6162.7 6343.9 6469.2 6629.2 6873.4 6394.7 6778.7 6790.3 6800.8 6808.2 5905.5 5955.0 6534.3 7023.0 7059.2 4690.0 4725.3 4732.6 4732.7 4738.6 6854.8 6871.4 6874.9 6874.9 6926.6 5550.8 6393.4 6541.3 6548.0 6646.6 5796.6 6485.4 6628.5 6656.4 6689.4 5652.0 6227.7 6527.5 6548.2 6737.0 5479.6 5881.9 6284.2 6669.6 6697.0 5590.7 6166.9 6182.4 6487.6 6576.5 00:56:24 INFO - PID 1652 | 00:56:24 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:24356.06 Median:16582.00 stddev:37860.52 (228.3%) stddev-sans-first:37925.48 00:56:24 INFO - PID 1652 | Values: 818.9 826.9 828.9 856.6 857.0 16591.0 16772.8 16818.7 16958.0 17006.8 11981.0 12642.7 13107.0 15797.7 16507.8 996.2 1007.0 1015.8 1019.4 1020.1 994.6 1004.5 1013.5 1018.7 1022.7 1075.4 1079.2 1080.8 1082.9 1087.3 899.8 987.4 1056.2 1067.5 1078.4 781.3 785.9 787.3 788.1 789.7 16573.0 17452.0 17533.5 17599.3 17606.2 17682.2 18132.8 18141.5 18249.2 18336.1 14175.9 14906.9 14984.2 15038.2 15058.8 3343.2 3523.0 3577.9 3603.5 3605.3 131458.3 155896.7 160432.2 163362.2 163711.0 13394.2 13500.5 13872.4 13909.0 13914.7 23449.3 26278.6 26754.5 26776.0 26857.0 7209.7 7967.2 8194.6 8196.9 8213.9 4005.6 4043.4 4057.7 4078.4 4083.9 4990.3 5183.6 5221.0 5226.3 5253.0 950.3 978.2 1034.2 1054.1 1055.6 646.0 647.1 654.2 678.0 679.1 10695.4 12204.3 12621.0 12689.9 12706.1 624.1 648.5 652.1 673.1 680.1 4765.9 5641.4 5644.4 5867.6 5918.4 127230.0 129038.0 129048.0 133889.0 139096.0 111334.8 111769.3 113951.4 115600.4 119056.8 91134.5 91173.5 94404.5 96168.5 96677.0 10756.9 10847.5 10920.9 10951.9 11561.0 41335.0 43519.1 43806.3 43969.6 44705.6 55007.0 55415.0 56228.2 57159.7 57502.7 15012.3 15141.2 15283.2 15411.0 15428.6 15779.8 16277.5 16316.8 16345.2 16458.1 14388.8 14504.6 15089.6 15122.5 15418.3 16251.8 17092.3 17184.5 17210.8 17460.1 15888.2 16051.4 16418.8 16467.4 16489.8 10334.6 11101.8 11537.5 11732.7 11766.5 12535.6 12648.7 12723.9 12795.6 13228.3 00:56:24 INFO - PID 1652 | -------- Summary: end -------- 00:56:24 INFO - PID 1652 | 00:56:24 INFO - TEST-INFO | 1652: exit 0 00:56:24 INFO - TEST-OK | dromaeo_css | took 1144170ms 00:56:24 INFO - TEST-START | kraken 00:56:24 INFO - operating with platform_type : mac_ 00:56:24 INFO - Initialising browser for kraken test... 00:56:24 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpQErg8X/profile 00:56:24 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground) 00:56:58 INFO - TEST-INFO | 1662: killed by SIGTERM 00:56:58 INFO - Browser initialized. 00:56:58 INFO - Running cycle 1/1 for kraken test... 00:56:58 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpQErg8X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:57:00 INFO - PID 1666 | 2017-06-12 00:57:00.386 plugin-container[1667:11695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c43, name = 'com.apple.tsm.portname' 00:57:00 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:57:00 INFO - PID 1666 | 2017-06-12 00:57:00.386 plugin-container[1667:11695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470f, name = 'com.apple.CFPasteboardClient' 00:57:00 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:57:00 INFO - PID 1666 | 2017-06-12 00:57:00.386 plugin-container[1667:11695] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:57:01 INFO - PID 1666 | 2017-06-12 00:57:01.184 plugin-container[1668:11742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 00:57:01 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:57:01 INFO - PID 1666 | 2017-06-12 00:57:01.185 plugin-container[1668:11742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0f, name = 'com.apple.CFPasteboardClient' 00:57:01 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:57:01 INFO - PID 1666 | 2017-06-12 00:57:01.185 plugin-container[1668:11742] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:57:10 INFO - PID 1666 | RSS: Main: 253718528 00:57:10 INFO - PID 1666 | 00:57:13 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection) 00:57:14 INFO - PID 1666 | RSS: Main: 265412608 00:57:14 INFO - PID 1666 | 00:57:16 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-dft) 00:57:17 INFO - PID 1666 | RSS: Main: 279449600 00:57:17 INFO - PID 1666 | 00:57:20 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-fft) 00:57:21 INFO - PID 1666 | RSS: Main: 282050560 00:57:21 INFO - PID 1666 | 00:57:23 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator) 00:57:24 INFO - PID 1666 | RSS: Main: 283697152 00:57:24 INFO - PID 1666 | 00:57:25 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:57:26 INFO - PID 1666 | RSS: Main: 285847552 00:57:26 INFO - PID 1666 | 00:57:29 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom) 00:57:30 INFO - PID 1666 | RSS: Main: 287371264 00:57:30 INFO - PID 1666 | 00:57:32 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate) 00:57:33 INFO - PID 1666 | RSS: Main: 289243136 00:57:33 INFO - PID 1666 | 00:57:36 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial) 00:57:37 INFO - PID 1666 | RSS: Main: 288399360 00:57:37 INFO - PID 1666 | 00:57:38 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:57:39 INFO - PID 1666 | RSS: Main: 288116736 00:57:39 INFO - PID 1666 | 00:57:40 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:57:41 INFO - PID 1666 | RSS: Main: 288653312 00:57:41 INFO - PID 1666 | 00:57:43 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:57:44 INFO - PID 1666 | RSS: Main: 287985664 00:57:44 INFO - PID 1666 | 00:57:47 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:57:47 INFO - PID 1666 | RSS: Main: 287563776 00:57:47 INFO - PID 1666 | 00:57:50 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:57:51 INFO - PID 1666 | RSS: Main: 287883264 00:57:51 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49223/tests/kraken/driver.html?testName=ai-astar) 00:57:53 INFO - PID 1666 | RSS: Main: 289456128 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | __start_tp_report 00:57:53 INFO - PID 1666 | _x_x_mozilla_page_load 00:57:53 INFO - PID 1666 | _x_x_mozilla_page_load_details 00:57:53 INFO - PID 1666 | |i|pagename|runs| 00:57:53 INFO - PID 1666 | |0;ai-astar;105;113;115;128;159;128;116;122;116;115 00:57:53 INFO - PID 1666 | |1;audio-beat-detection;137;112;145;172;144;125;111;154;143;93 00:57:53 INFO - PID 1666 | |2;audio-dft;200;197;200;216;221;221;204;201;200;195 00:57:53 INFO - PID 1666 | |3;audio-fft;64;77;94;159;84;87;68;61;120;64 00:57:53 INFO - PID 1666 | |4;audio-oscillator;68;74;113;73;74;86;109;69;71;115 00:57:53 INFO - PID 1666 | |5;imaging-gaussian-blur;90;91;89;88;90;88;88;89;97;89 00:57:53 INFO - PID 1666 | |6;imaging-darkroom;96;97;96;100;98;97;95;97;97;97 00:57:53 INFO - PID 1666 | |7;imaging-desaturate;92;94;90;92;92;92;90;95;92;90 00:57:53 INFO - PID 1666 | |8;json-parse-financial;53;50;52;51;53;52;52;53;54;54 00:57:53 INFO - PID 1666 | |9;json-stringify-tinderbox;59;58;56;74;63;64;59;46;65;76 00:57:53 INFO - PID 1666 | |10;stanford-crypto-aes;80;81;79;85;96;69;86;76;75;73 00:57:53 INFO - PID 1666 | |11;stanford-crypto-ccm;159;139;134;138;144;141;140;138;143;166 00:57:53 INFO - PID 1666 | |12;stanford-crypto-pbkdf2;177;179;179;183;179;179;182;217;178;179 00:57:53 INFO - PID 1666 | |13;stanford-crypto-sha256-iterative;67;67;64;66;68;70;68;67;67;65 00:57:53 INFO - PID 1666 | __end_tp_report 00:57:53 INFO - PID 1666 | __start_cc_report 00:57:53 INFO - PID 1666 | _x_x_mozilla_cycle_collect,1164 00:57:53 INFO - PID 1666 | __end_cc_report 00:57:53 INFO - PID 1666 | __startTimestamp1497254273509__endTimestamp 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | ------- Summary: start ------- 00:57:53 INFO - PID 1666 | Number of tests: 14 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#0] ai-astar Cycles:10 Average:121.70 Median:119.00 stddev:14.83 (12.5%) stddev-sans-first:14.45 00:57:53 INFO - PID 1666 | Values: 105.0 113.0 115.0 128.0 159.0 128.0 116.0 122.0 116.0 115.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:144.50 stddev:23.40 (16.2%) stddev-sans-first:24.79 00:57:53 INFO - PID 1666 | Values: 137.0 112.0 145.0 172.0 144.0 125.0 111.0 154.0 143.0 93.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#2] audio-dft Cycles:10 Average:205.50 Median:202.50 stddev:9.92 (4.9%) stddev-sans-first:10.33 00:57:53 INFO - PID 1666 | Values: 200.0 197.0 200.0 216.0 221.0 221.0 204.0 201.0 200.0 195.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#3] audio-fft Cycles:10 Average:87.80 Median:72.50 stddev:30.77 (42.4%) stddev-sans-first:31.41 00:57:53 INFO - PID 1666 | Values: 64.0 77.0 94.0 159.0 84.0 87.0 68.0 61.0 120.0 64.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:72.00 stddev:19.40 (26.9%) stddev-sans-first:19.55 00:57:53 INFO - PID 1666 | Values: 68.0 74.0 113.0 73.0 74.0 86.0 109.0 69.0 71.0 115.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:89.50 stddev:2.69 (3.0%) stddev-sans-first:2.85 00:57:53 INFO - PID 1666 | Values: 90.0 91.0 89.0 88.0 90.0 88.0 88.0 89.0 97.0 89.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:97.00 stddev:1.33 (1.4%) stddev-sans-first:1.36 00:57:53 INFO - PID 1666 | Values: 96.0 97.0 96.0 100.0 98.0 97.0 95.0 97.0 97.0 97.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:92.00 stddev:1.66 (1.8%) stddev-sans-first:1.76 00:57:53 INFO - PID 1666 | Values: 92.0 94.0 90.0 92.0 92.0 92.0 90.0 95.0 92.0 90.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.00 stddev:1.26 (2.4%) stddev-sans-first:1.32 00:57:53 INFO - PID 1666 | Values: 53.0 50.0 52.0 51.0 53.0 52.0 52.0 53.0 54.0 54.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:63.50 stddev:8.69 (13.7%) stddev-sans-first:9.15 00:57:53 INFO - PID 1666 | Values: 59.0 58.0 56.0 74.0 63.0 64.0 59.0 46.0 65.0 76.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.50 stddev:7.67 (9.5%) stddev-sans-first:8.14 00:57:53 INFO - PID 1666 | Values: 80.0 81.0 79.0 85.0 96.0 69.0 86.0 76.0 75.0 73.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:142.00 stddev:10.17 (7.2%) stddev-sans-first:9.28 00:57:53 INFO - PID 1666 | Values: 159.0 139.0 134.0 138.0 144.0 141.0 140.0 138.0 143.0 166.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.20 Median:179.00 stddev:12.01 (6.7%) stddev-sans-first:12.52 00:57:53 INFO - PID 1666 | Values: 177.0 179.0 179.0 183.0 179.0 179.0 182.0 217.0 178.0 179.0 00:57:53 INFO - PID 1666 | 00:57:53 INFO - PID 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.66 (2.5%) stddev-sans-first:1.76 00:57:53 INFO - PID 1666 | Values: 67.0 67.0 64.0 66.0 68.0 70.0 68.0 67.0 67.0 65.0 00:57:53 INFO - PID 1666 | -------- Summary: end -------- 00:57:53 INFO - PID 1666 | 00:57:54 INFO - TEST-INFO | 1666: exit 0 00:57:54 INFO - TEST-OK | kraken | took 89617ms 00:57:54 INFO - SUITE-END | took 1233s 00:57:54 INFO - Completed test suite (00:20:34) 00:57:54 INFO - javascript benchmark 00:57:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8271.995640154812, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3445.638207988163, 3471.149568355753, 3571.4642803579477, 3578.9463158052636, 3590.6409359064082, 6140.62695141751, 6160.168377269068, 6161.784337548187, 6204.78283260086, 6246.09431632413, 6216.937830621692, 6227.53293502988, 6233.532485063627, 6251.71867265973, 6260.624362538239, 5451.618386712941, 5456.072467680504, 5456.399796022434, 5523.558115350783, 5583.190437386576, 5482.122860342346, 5503.071910056459, 5544.085225937715, 5577.107662773957, 5587.888242235153, 4511.413516242867, 5323.813666521653, 5404.972975135139, 5502.504774570288, 5524.751386187631, 4639.234526303156, 5144.742762861842, 5363.570914326883, 5457.754401051962, 5531.944680553223, 4887.266909963499, 4980.800767969313, 4983.803887067078, 5405.594580406485, 5536.197251398565, 6203.1005504202085, 6247.750089996394, 6271.749130034793, 6275.811725648238, 6288.276848162474, 6484.675766211671, 6702.296258892844, 6725.899111513331, 6740.865182696368, 6764.729410823561, 6637.3030831762935, 6713.99290106483, 6720.0, 6798.320167983212, 6799.898001529981, 5847.649141051551, 5913.817236552707, 6264.060390941349, 6267.843303917347, 6340.3659634036685, 6941.271166527543, 6943.097397338414, 6944.895826562605, 6970.756023539152, 6999.510034297652, 6361.777337793155, 6442.226932768098, 6460.321666224979, 6585.506087043491, 6639.203295604461, 6588.8023359299295, 6593.86812263752, 6595.835104122435, 6598.670066496656, 6600.471962243009, 6535.8692826143215, 6547.9672601636685, 6599.274079851212, 6603.80188594343, 6613.041109039163, 6705.497087718343, 6725.024871393621, 6727.730890764413, 6732.596044237362, 6751.122354093936, 5315.3090098286975, 5358.19627055937, 5364.758585863646, 5382.138857782736, 5390.703511306916, 4761.7142971421135, 4773.71357718538, 4776.47458779534, 4792.4249090108715, 4820.855374338776, 6130.724117414727, 6275.717592708339, 6306.810795676137, 6316.9368306317265, 6358.936410635927, 6675.232348279913, 6688.023829395872, 6757.831054223684, 6773.661316934232, 6792.93207067923, 5010.924836127461, 6324.841878953063, 6327.240731112296, 6355.650439225795, 6362.300146983828, 5973.342932277446, 5978.372270911578, 5979.4618484336615, 5980.372060933626, 6005.669688167105, 6963.512554121163, 6995.160580730447, 6999.0551275577145, 7019.824504387431, 7031.753888613874, 6536.248331441755, 6638.435732962752, 6825.726970921108, 6836.897446538405, 6880.380765730908, 6783.4573234142135, 6840.076589660531, 6893.75871844483, 6970.407515361251, 6977.616231107338, 6601.438877695257, 6677.332266773284, 6720.798376048726, 6720.966395167993, 6746.966265168643, 6834.0, 6835.521513494008, 6854.6229957352825, 6865.588064716133, 6872.10662613857, 6521.5545019908905, 6552.541322107598, 6556.770513032021, 6559.639219842955, 6569.540132190701, 6290.087937248982, 6371.0, 6384.457321127757, 6592.340765923369, 6594.802155935329, 5626.971865140812, 5830.833833233455, 6240.469560087444, 6329.082283069021, 6396.520260980352, 6548.639824809681, 6555.2133743951035, 6571.967140164268, 6580.967095164494, 6621.801345959629, 6370.331115232834, 6389.361063893573, 6390.201224846895, 6397.976323788172, 6398.0083087121575], "value": 6105.9880425589035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.7272182014474, 816.975490735278, 817.6238930092157, 822.6668199379252, 864.5849992003838, 28766.424671506556, 37111.81444092779, 40161.59838401611, 40187.0, 40271.395929061015, 39553.0, 39570.8021459893, 39808.0, 39965.0, 40042.19915601686, 7482.588457634828, 7609.277118673717, 7626.351760100398, 7626.809329766745, 7640.083190017206, 7279.67241474135, 8270.669173233064, 8530.57347132644, 8561.785955351104, 8567.74296771098, 8591.656333746643, 8638.870416943751, 8655.740327790176, 8667.479951202948, 8668.479891206516, 8561.95719021407, 8609.182127697859, 8621.0, 8630.223279904838, 8646.65413383467, 8306.127856575033, 8314.293285070777, 8317.334613230987, 8363.0, 8377.0, 37975.24049519022, 38230.0, 38312.80843595792, 38328.233435331415, 38498.80750596257, 3599.0, 3622.3479773640734, 3641.3263546243766, 3645.38028535147, 3650.4524321351746, 30963.22591935197, 31082.222944426343, 31091.533626995613, 31129.844350778327, 31200.0, 3076.446239676857, 3121.750259979196, 3143.2141964508874, 3150.936981260362, 3179.9682003179905, 249946.0, 252678.0, 253557.0, 313746.0, 317598.0, 48921.510784891696, 49779.0, 49793.004139917, 49945.001099977795, 50091.0, 52139.0, 52438.47561524412, 52575.737121314145, 52597.68505787386, 52685.0, 14143.151410915378, 14237.572872813833, 14308.0, 14347.282635868165, 14371.92814035923, 11571.537138514364, 11574.0, 11591.826122608167, 11593.304401735923, 11595.768084638428, 12020.63938081859, 13846.515371962134, 13847.584572462842, 13911.652208694864, 13996.650083747987, 5350.9732451337495, 5852.619579727304, 5906.675132867647, 6009.248843894513, 6022.638641681426, 986.3440811860177, 991.7371896447447, 991.7619771254847, 1051.6897515233024, 1056.5351245451977, 23298.417539561648, 23488.882555587454, 23571.0, 23674.526509469713, 24579.508409831702, 1089.9455027248605, 1097.7475180708482, 1149.706824759681, 1154.318951818431, 1156.4506859241792, 11957.820632690517, 12704.491820327268, 12783.977281817432, 12810.615681529569, 12897.871021289668, 260597.0, 318561.0, 319053.4047329748, 319743.0, 319772.0, 50785.73035674138, 56468.71765641228, 56696.0, 56805.71597141988, 56811.0, 1451.267110109409, 1487.7768334749874, 1538.7384144695204, 1547.002183591577, 1553.2932515705327, 22320.441988949755, 23031.539369212856, 24483.265502034967, 28573.0, 30495.390092197587, 52184.73907630437, 52354.47645523496, 52510.0, 52584.0, 52695.946081077396, 256041.0, 256623.0, 257667.0, 257676.0, 258349.0, 35140.06729629895, 35714.0, 35819.462708058876, 36506.452403214505, 36601.26797463983, 42936.57063429326, 43706.562934370246, 44120.779396102815, 44188.11623767571, 45038.0992380144, 28911.42177156487, 30443.558459985245, 30737.846310768302, 31366.52950205703, 31482.212944676565, 29996.85001574978, 30456.543151852296, 31382.0, 31396.529052064696, 31467.84266078655, 36934.44598331081, 37834.0541486454, 38630.22739545137, 38635.6136438632, 38915.61084389233, 30755.077347679606, 30859.537106943862, 31882.0, 32001.839990799897, 32478.0, 20699.336904358814, 23152.652710209022, 24949.376265593506, 25146.37134071663, 25470.0], "value": 17677.207186320124, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.7252734622899, 481.63877092180866, 497.9651424400291, 511.7602704740594, 528.4688887667893, 21730.0, 21878.671819922674, 22381.44046398841, 22406.88796556019, 22473.0, 23283.534329313447, 23799.881000594974, 24817.37956551087, 24885.253442396755, 24922.87538562309, 6323.810285691425, 6461.0, 6463.644499552523, 6479.384458476437, 6484.546081774278, 6428.485721142298, 6458.741650334005, 6470.191226096738, 6479.902801457982, 6483.027545868111, 5797.130430435426, 6309.77915772948, 6327.905081423783, 6359.55483116182, 6388.584741991755, 6343.0, 6396.776112836028, 6396.968015159941, 6397.0, 6409.198850143732, 5837.066069428871, 5845.561582881301, 5856.736446859902, 5860.267466566679, 5862.941370586324, 27820.86089569539, 29988.8500557498, 30257.69742302593, 30499.08502744899, 30639.846800766078, 2259.6271615183473, 2277.533105713325, 2303.67748515208, 2304.585174668559, 2304.9078036878504, 18822.435326940213, 19319.323823666244, 19368.225270989144, 19395.030248487517, 19424.028798560157, 2185.726784151981, 2211.3365990202874, 2245.057076028072, 2250.1561914282142, 2266.7166604174477, 161025.0, 166101.16949415175, 170954.0, 171355.14322428431, 189816.0, 13703.21858158453, 13833.584992450242, 14733.0, 15218.467353642569, 15231.390744370101, 20157.697634535492, 20203.595928081355, 20339.8983005087, 20414.591708165754, 20419.8979005104, 9593.808123837483, 9607.375520591138, 9666.0, 9725.708228753148, 9814.509274536245, 7142.0, 7673.808154796175, 8995.640174392951, 9008.648702694518, 9065.954670226738, 7639.465237433326, 9033.683821066232, 9117.270618350516, 9190.264778817678, 9208.388532006871, 2610.2691246451022, 2612.333854867035, 2623.3310505820896, 2631.0265201875427, 2768.806183567171, 826.1985873702499, 957.3968399908014, 959.712086374085, 984.3503287829998, 1009.5658866687248, 20067.0, 20230.089645965967, 20308.593828123354, 21429.892850535958, 21490.78509214919, 939.1641439119189, 973.4889183178747, 984.3011461862015, 1018.2312354172552, 1031.2729525684374, 10591.523381447852, 10704.250702450909, 10704.447855060973, 10746.623868164734, 11091.889081109248, 538095.0, 580972.0951395216, 583770.0, 587240.0, 592936.0, 152975.0, 160820.0, 169205.0, 172067.0, 175797.0, 1040.505759764106, 1096.550414330121, 1100.6587957732968, 1216.197309775544, 1221.9266843989212, 20645.58708825845, 21126.788732112484, 21330.89334553317, 23239.419014524774, 24048.51902961966, 53819.73090134681, 64034.0, 67548.3245167542, 67681.66159169172, 69817.65091174512, 83540.58229708813, 92774.0, 93805.0, 95854.0, 96331.51834240784, 15872.679147401526, 16027.919860400623, 16602.25289861935, 16786.992780433215, 16869.240884159997, 17737.024463654623, 18147.637047258715, 18370.908145459187, 18794.53013674669, 18976.051197439796, 13040.739185216433, 15227.771583426036, 15534.1456219909, 15551.533453996399, 15672.05967641945, 13111.40998655082, 13474.66313342135, 13541.79687304711, 13755.0, 13995.860041399455, 15551.688966220838, 15611.141387223812, 15638.609034774221, 15742.449014284444, 16141.354345826036, 16868.746968795233, 16895.32418703238, 16920.40778572744, 17029.65940681155, 17218.483445496655, 14457.421703132177, 15030.398784048515, 15043.774343384639, 15114.697706046038, 15231.847681523042], "value": 11914.123150706779, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.6420766393073, 1451.5355086372365, 1472.8158980127484, 1473.0498828255775, 1497.805285312909, 12961.287129207889, 13480.0, 13535.255560944157, 13544.05191636586, 13606.251656158907, 12968.481260749559, 13417.127886687358, 13433.261170635635, 13434.059615826896, 13449.193048417077, 2695.4204845958116, 2745.574435962429, 2748.051922086877, 2748.876300566469, 2756.4073724149303, 2244.9887750561224, 2350.3771500552366, 2664.840109593421, 2722.3058120179326, 2724.6730392352947, 2298.3564601911494, 2314.976850231493, 2554.220962606404, 2635.8813853376646, 2648.3246772073094, 2691.7039125696156, 2693.8653067346654, 2696.8516731579757, 2698.406350602874, 2700.891964321425, 2519.8236123471365, 2529.380301826046, 2533.8226324157317, 2541.7077036140895, 2542.974570254311, 10685.893141068645, 13041.347932603427, 13049.021323400782, 13138.146020508637, 13140.34298285082, 7244.963775181091, 7506.174320824705, 7513.849723005509, 7535.095788505359, 7541.208173141851, 3550.260372417523, 3725.3666876631037, 4630.981184139484, 4634.050019745978, 4652.767361631905, 1354.356680576728, 1364.9249291288975, 1365.1467832604621, 1387.8473367929519, 1395.644110751752, 19394.321198758116, 19419.22323107074, 19543.0228488575, 19656.115474803668, 19691.60616787656, 5884.7940322088525, 6436.903446448214, 6451.387118223761, 6454.483641308684, 6612.900806487906, 13058.738825223443, 13969.79045314321, 13992.950528710184, 13995.0, 14005.579832605039, 7108.07595012645, 7273.818154546178, 7278.381337586365, 7332.5967071811565, 7347.485676002629, 6548.1814773153355, 6724.529282950244, 6753.257141714407, 6813.080234168423, 6820.624865632438, 4240.363945408213, 4798.976005120022, 4915.066137433876, 4935.407751069894, 4958.0, 1437.741206582825, 1441.077710265431, 1447.1534152520683, 1450.100937418786, 1471.0952764050032, 767.439768968656, 818.9590520473953, 837.6105111123235, 852.2542775071812, 854.6880388658095, 6879.828004299932, 6946.444284457231, 7005.474589405815, 7233.168185658611, 7260.782176534713, 937.2174234514121, 946.6071580294239, 982.9727934308664, 984.2716389871443, 1019.7807471393686, 4221.957780422218, 4256.446661933929, 4264.701470897069, 4307.956920430818, 4538.795754191069, 17162.742558861628, 17798.733019004725, 17832.93002419833, 17838.821611783977, 17857.821421785877, 17685.292588296325, 17697.292108315523, 17704.645907081787, 17710.645787084188, 17757.644847103245, 8489.151084891584, 8762.211400973943, 8977.551122443982, 9115.726528204032, 9202.861957070649, 7783.688652454065, 7879.606019698877, 8055.718049868226, 8057.677692892453, 8368.83262334762, 15360.615984600474, 15487.380504779683, 15945.202739863193, 16152.596185095466, 16157.75763363574, 15475.613109671898, 16152.353905843634, 16180.919095404448, 16345.26446309895, 16554.0, 10623.734406639587, 10832.0, 10868.967448092419, 10870.184736144663, 10871.891281087404, 12101.213421127599, 12156.209846359958, 12217.328046957502, 12243.571474998333, 12253.571125010581, 8962.73111806647, 9575.760605984906, 9650.131488166098, 9759.268054895769, 9963.0, 9372.297077719335, 9507.524623768923, 9562.808743824946, 9675.322727409013, 9940.005999400291, 8541.0, 8894.0, 8928.464292142493, 9006.144416280444, 9067.09329067088, 5841.591088623755, 6461.64460954652, 6675.532712710064, 6823.590584564942, 6910.032595436744, 6793.422559082534, 6818.21590517077, 6984.511084224056, 7124.643767811693, 7161.606111663908], "value": 5930.504723431091, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.1923654674394, 426.3264042812357, 435.01252157602244, 439.8834308908139, 464.14828789171867, 6342.175517182765, 6374.585651932621, 6375.776847810327, 6380.649064301461, 6387.712552935118, 5469.480399362054, 5975.223220981277, 6176.660283684396, 6188.876222475559, 6208.6585237811905, 4706.9764651176865, 4710.623150147997, 4722.693024953384, 4738.715677059387, 4747.52524747526, 4516.435445569304, 4689.437267527885, 4712.670113092085, 4727.2036626082045, 4770.85687429376, 4462.040661257829, 4481.663875209356, 4665.626749860003, 4688.9765551172195, 4694.3662605548325, 4537.684071619226, 4635.351050852883, 4679.134360143385, 4680.742559159245, 4689.9531004690025, 3318.734501239895, 3354.3962086824363, 3766.0773110587807, 4032.717709760318, 4113.629773320416, 5299.178627312773, 5650.971745141289, 5979.9402005980255, 5995.880082398371, 6012.789552365646, 5515.806946756885, 5517.310336207974, 5530.695811730353, 5531.308586426697, 5540.639858409231, 6591.4397276231575, 6643.468522518187, 6654.833629159261, 6666.300038495985, 6679.866402671968, 3925.1168487090463, 4190.497140343178, 4654.2785868190485, 4661.324108004321, 4667.603253723438, 6002.3697511761075, 6244.0, 6554.082428460019, 7013.298670132997, 7103.8934415983285, 4144.958550414518, 4543.4775000875325, 4766.189747742892, 4768.380110585602, 4775.2359622460235, 4497.9325310120375, 4837.903241935212, 4842.58837998774, 4863.1732605457155, 4874.098291816025, 5140.974295128501, 5524.640898341595, 5538.141588053858, 5539.861503462445, 5581.358143813513, 5581.911527252147, 6135.447809697149, 6138.478229350466, 6544.70548825304, 6693.364130407604, 1397.1438828832495, 1536.4622382166376, 1542.8225754038428, 1626.544567521096, 1633.5262773795466, 950.6102497975796, 967.5936106835146, 1109.545086514525, 1124.1624989382963, 1128.5203788389902, 5888.617239879394, 6306.2747784004505, 6310.337414571404, 6346.365363463617, 6375.808725738235, 5251.36983561975, 6036.245469316335, 6847.486438517131, 6892.448604111659, 6947.548409353376, 6042.942485065096, 6237.750489980441, 6279.869623467728, 6299.74005198962, 6313.179286692701, 3545.6986156177013, 3554.489342029627, 3567.340042092221, 3569.428891377367, 3585.874494392736, 7024.929750702529, 7035.542689725152, 7044.577325360392, 7048.0, 7057.117860267466, 6897.482553838147, 7024.192217895007, 7033.331833475811, 7072.858542829114, 7080.610566418896, 5764.682942438206, 7040.3663670269925, 7063.505554611128, 7072.524616384224, 7112.253213412517, 6162.722677479434, 6343.873122537431, 6469.223693156851, 6629.204495460575, 6873.381395674413, 6394.6659469292445, 6778.695528339441, 6790.32096790317, 6800.761973330909, 6808.217055038734, 5905.4685078343155, 5954.970225148846, 6534.281229064704, 7022.964885175541, 7059.18819335783, 4690.0, 4725.267583524559, 4732.621390288838, 4732.692374995614, 4738.5833225054785, 6854.760083397057, 6871.381575658214, 6874.8625027500175, 6874.931250687429, 6926.58440493572, 5550.833474995757, 6393.424591786761, 6541.345865413611, 6547.9672601636685, 6646.60120392778, 5796.62321949072, 6485.416312531895, 6628.5028622852515, 6656.400923916871, 6689.397954183952, 5652.0, 6227.657478838526, 6527.51043671717, 6548.214214294315, 6736.966315168393, 5479.561635069105, 5881.852953676193, 6284.214473190851, 6669.566478178903, 6697.0, 5590.748416321194, 6166.876662466815, 6182.350853160533, 6487.610743355413, 6576.506761992965], "value": 4881.20773075341, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.8567000774863, 826.9131741167179, 828.8508068547662, 856.6444925355976, 857.0058731871031, 16591.0, 16772.832271677296, 16818.66362672746, 16957.982521048747, 17006.82993170068, 11981.0, 12642.683932901682, 13107.016973726984, 15797.684046319067, 16507.83492165078, 996.1731762637012, 1007.028217769852, 1015.7562185075602, 1019.3985548526368, 1020.0717347214037, 994.6469003503761, 1004.5278719002057, 1013.5236438873754, 1018.6638409324942, 1022.7034160093564, 1075.3977772447415, 1079.1582565598806, 1080.8108580998332, 1082.8971247731454, 1087.271528076187, 899.7930475990526, 987.3878195518753, 1056.1603525197486, 1067.4716015572342, 1078.385320367391, 781.2734157233771, 785.9174786647434, 787.2914377060634, 788.1212448120361, 789.6643926331286, 16573.005619662857, 17452.040137792417, 17533.473995780147, 17599.296028158857, 17606.2077206526, 17682.204300806494, 18132.818671813246, 18141.54646133844, 18249.17878695462, 18336.083195840154, 14175.929120354436, 14906.850931490655, 14984.175870327128, 15038.172900490468, 15058.774118388234, 3343.2477692519233, 3523.0, 3577.8568857245677, 3603.5135256740527, 3605.3149901518627, 131458.34270828776, 155896.66155007685, 160432.19783901007, 163362.1831890833, 163711.0, 13394.196348219139, 13500.5274815381, 13872.445102196001, 13909.0, 13914.721705565831, 23449.296521104396, 26278.6058209127, 26754.464910701678, 26776.0, 26857.0, 7209.71161153548, 7967.2431119043595, 8194.590270486451, 8196.877046844302, 8213.876791848126, 4005.5994400560294, 4043.4137050128156, 4057.6753859691153, 4078.388241763759, 4083.85706500277, 4990.251462280687, 5183.637145399861, 5221.0, 5226.268322434862, 5253.028189784902, 950.3205208275978, 978.1636700620867, 1034.2243317511866, 1054.146141625286, 1055.5619417941623, 645.9515536334794, 647.0682217606632, 654.1659384285075, 678.0168755304828, 679.0628932073805, 10695.358278503314, 12204.267743935392, 12620.990320774315, 12689.936550317188, 12706.110572259853, 624.1137584629837, 648.5298158834902, 652.0935899100255, 673.1182151381705, 680.0750978669006, 4765.928511072337, 5641.351244606841, 5644.3508996465935, 5867.618604790675, 5918.408159184133, 127230.0, 129037.97382089378, 129048.0, 133889.0, 139096.0, 111334.81552638492, 111769.32346014817, 113951.4302428499, 115600.42199789116, 119056.80943190458, 91134.54432727926, 91173.54413228024, 94404.52797735967, 96168.51915740517, 96677.0, 10756.892431075588, 10847.474156426822, 10920.890791091988, 10951.890481095086, 11561.0, 41335.0, 43519.12961740684, 43806.34290485582, 43969.560304396546, 44705.55294447144, 55007.0, 55415.0, 56228.15657765055, 57159.714201428724, 57502.71248643898, 15012.324445400202, 15141.242937852843, 15283.159426231665, 15410.998285111433, 15428.614284642525, 15779.842201577838, 16277.511674649779, 16316.836831631532, 16345.182740863147, 16458.094804785374, 14388.784168237275, 14504.564863054125, 15089.622759431102, 15122.54632361031, 15418.306176221873, 16251.756223656419, 17092.316307347566, 17184.484465466056, 17210.82789172092, 17460.12699365052, 15888.2055897207, 16051.438199663422, 16418.83581164173, 16467.423640172536, 16489.752653709966, 10334.638287659896, 11101.777964440827, 11537.48081336325, 11732.70668233301, 11766.470508827295, 12535.623931282076, 12648.747025059263, 12723.872761272269, 12795.552155674506, 13228.338583071001], "value": 8605.603851708793, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 115.0, 128.0, 159.0, 128.0, 116.0, 122.0, 116.0, 115.0], "value": 121.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 112.0, 145.0, 172.0, 144.0, 125.0, 111.0, 154.0, 143.0, 93.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 197.0, 200.0, 216.0, 221.0, 221.0, 204.0, 201.0, 200.0, 195.0], "value": 205.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 77.0, 94.0, 159.0, 84.0, 87.0, 68.0, 61.0, 120.0, 64.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 113.0, 73.0, 74.0, 86.0, 109.0, 69.0, 71.0, 115.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 89.0, 88.0, 90.0, 88.0, 88.0, 89.0, 97.0, 89.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 100.0, 98.0, 97.0, 95.0, 97.0, 97.0, 97.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 90.0, 92.0, 92.0, 92.0, 90.0, 95.0, 92.0, 90.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 52.0, 51.0, 53.0, 52.0, 52.0, 53.0, 54.0, 54.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 56.0, 74.0, 63.0, 64.0, 59.0, 46.0, 65.0, 76.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 85.0, 96.0, 69.0, 86.0, 76.0, 75.0, 73.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 139.0, 134.0, 138.0, 144.0, 141.0, 140.0, 138.0, 143.0, 166.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 179.0, 179.0, 183.0, 179.0, 179.0, 182.0, 217.0, 178.0, 179.0], "value": 183.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 64.0, 66.0, 68.0, 70.0, 68.0, 67.0, 67.0, 65.0], "value": 66.9, "unit": "score"}]}]} 00:57:54 INFO - Return code: 0 00:57:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:57:54 INFO - # TBPL SUCCESS # 00:57:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:57:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:57:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:57:54 INFO - 2017-06-12 00:57:54.922 screenresolution[1674:12150] starting screenresolution argv=screenresolution get 00:57:54 INFO - 2017-06-12 00:57:54.925 screenresolution[1674:12150] Display 0: 1600x1200x32@60 00:57:54 INFO - 2017-06-12 00:57:54.931 screenresolution[1675:12153] starting screenresolution argv=screenresolution list 00:57:54 INFO - Available Modes on Display 0 00:57:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:57:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:57:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:57:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:57:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:57:55 INFO - Intel Iris: 00:57:55 INFO - Chipset Model: Intel Iris 00:57:55 INFO - Type: GPU 00:57:55 INFO - Bus: Built-In 00:57:55 INFO - VRAM (Dynamic, Max): 1536 MB 00:57:55 INFO - Vendor: Intel (0x8086) 00:57:55 INFO - Device ID: 0x0a2e 00:57:55 INFO - Revision ID: 0x0009 00:57:55 INFO - Displays: 00:57:55 INFO - Display: 00:57:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:57:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:57:55 INFO - Display Serial Number: 1600x1200 60 00:57:55 INFO - Main Display: Yes 00:57:55 INFO - Mirror: Off 00:57:55 INFO - Online: Yes 00:57:55 INFO - Rotation: Supported 00:57:55 INFO - Return code: 0 00:57:55 INFO - Running post-action listener: _resource_record_post_action 00:57:55 INFO - [mozharness: 2017-06-12 07:57:55.169027Z] Finished run-tests step (success) 00:57:55 INFO - Running post-run listener: _resource_record_post_run 00:57:55 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 35320832; Write bytes: 577430528; Read time: 385; Write time: 1812 00:57:55 INFO - TinderboxPrint: CPU usage
53.3% 00:57:55 INFO - TinderboxPrint: I/O read bytes / time
35,320,832 / 385 00:57:55 INFO - TinderboxPrint: I/O write bytes / time
577,430,528 / 1,812 00:57:55 INFO - TinderboxPrint: CPU idle
2,347.7 (46.7%) 00:57:55 INFO - TinderboxPrint: CPU system
1,022.9 (20.3%) 00:57:55 INFO - TinderboxPrint: CPU user
1,659.0 (33.0%) 00:57:55 INFO - TinderboxPrint: Swap in / out
284,479,488 / 0 00:57:55 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250092032; Write bytes: 244512768; Read time: 18064; Write time: 533 00:57:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 00:57:55 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 33108992; Write bytes: 320490496; Read time: 360; Write time: 1245 00:57:55 INFO - Running post-run listener: _upload_blobber_files 00:57:55 INFO - Blob upload gear active. 00:57:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:57:55 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 00:57:55 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'] 00:57:55 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 00:57:56 INFO - (blobuploader) - INFO - Open directory for files ... 00:57:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:57:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:57:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:57:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:57:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:57:57 INFO - (blobuploader) - INFO - Done attempting. 00:57:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:57:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:57:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:57:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:57:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:57:58 INFO - (blobuploader) - INFO - Done attempting. 00:57:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:57:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:57:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:57:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:57:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:57:59 INFO - (blobuploader) - INFO - Done attempting. 00:57:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:57:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:57:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:58:01 INFO - (blobuploader) - INFO - Done attempting. 00:58:01 INFO - (blobuploader) - INFO - Iteration through files over. 00:58:01 INFO - Return code: 0 00:58:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:58:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:58:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4012896cb9ea91c588265cbcd17cfeed8be51875d171cf2b141178d9065779e9d11367e08388a763a9c6f41328b0df413c757cb5672707bcb6ff4c6108070e8a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26389eabf57b38cdd654491c2ce35f411d2f26a46270b4eb2ea5f43144c3daa36133ad68022b46366e113a9c2b2fde20e062bd84a8d8cdeac34b3fbe692da641", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f726f87de04222550b796fc22d67e90ad16947c71588fd205ce632b4d81cc4656769765d332f5cb8e47854c837f658865f7aa465ed8a007c1e0583cd7227bf41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b59dded6c4842dbfb4c0e89313072015c7b7df5ff5a2ef375d65703ad1cad92473f7c53d18c77ea38ddc7eabe9bc2bb3467f729077caaf841e699194c560f25f"} 00:58:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:58:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:58:01 INFO - Contents: 00:58:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4012896cb9ea91c588265cbcd17cfeed8be51875d171cf2b141178d9065779e9d11367e08388a763a9c6f41328b0df413c757cb5672707bcb6ff4c6108070e8a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26389eabf57b38cdd654491c2ce35f411d2f26a46270b4eb2ea5f43144c3daa36133ad68022b46366e113a9c2b2fde20e062bd84a8d8cdeac34b3fbe692da641", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f726f87de04222550b796fc22d67e90ad16947c71588fd205ce632b4d81cc4656769765d332f5cb8e47854c837f658865f7aa465ed8a007c1e0583cd7227bf41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b59dded6c4842dbfb4c0e89313072015c7b7df5ff5a2ef375d65703ad1cad92473f7c53d18c77ea38ddc7eabe9bc2bb3467f729077caaf841e699194c560f25f"} 00:58:01 INFO - Running post-run listener: copy_logs_to_upload_dir 00:58:01 INFO - Copying logs to upload dir... 00:58:01 INFO - mkdir: /builds/slave/test/build/upload/logs 00:58:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.923557 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-12 00:58:01.421243) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 00:58:01.424740) ========= 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.pubefrYdV1/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.xf3pA1gg0Z/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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/4012896cb9ea91c588265cbcd17cfeed8be51875d171cf2b141178d9065779e9d11367e08388a763a9c6f41328b0df413c757cb5672707bcb6ff4c6108070e8a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26389eabf57b38cdd654491c2ce35f411d2f26a46270b4eb2ea5f43144c3daa36133ad68022b46366e113a9c2b2fde20e062bd84a8d8cdeac34b3fbe692da641", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f726f87de04222550b796fc22d67e90ad16947c71588fd205ce632b4d81cc4656769765d332f5cb8e47854c837f658865f7aa465ed8a007c1e0583cd7227bf41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b59dded6c4842dbfb4c0e89313072015c7b7df5ff5a2ef375d65703ad1cad92473f7c53d18c77ea38ddc7eabe9bc2bb3467f729077caaf841e699194c560f25f"} build_url:https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011031 build_url: 'https://queue.taskcluster.net/v1/task/Ov8fZBRbRyeROZXMG0K31Q/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/4012896cb9ea91c588265cbcd17cfeed8be51875d171cf2b141178d9065779e9d11367e08388a763a9c6f41328b0df413c757cb5672707bcb6ff4c6108070e8a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26389eabf57b38cdd654491c2ce35f411d2f26a46270b4eb2ea5f43144c3daa36133ad68022b46366e113a9c2b2fde20e062bd84a8d8cdeac34b3fbe692da641", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f726f87de04222550b796fc22d67e90ad16947c71588fd205ce632b4d81cc4656769765d332f5cb8e47854c837f658865f7aa465ed8a007c1e0583cd7227bf41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b59dded6c4842dbfb4c0e89313072015c7b7df5ff5a2ef375d65703ad1cad92473f7c53d18c77ea38ddc7eabe9bc2bb3467f729077caaf841e699194c560f25f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 00:58:01.458238) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 00:58:01.458625) ========= 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.pubefrYdV1/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.xf3pA1gg0Z/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.005123 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 00:58:01.507230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 00:58:01.507579) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 00:58:01.507895) ========= ========= Total master_lag: 0.18 =========