builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0025 starttime: 1497439179.13 results: success (0) revision: db144665b097fc65b282aa75ed9372fc164f2f51 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:39.133902) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:39.134349) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:39.134623) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:39.157923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:39.158314) ========= 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.8yu64x1wJH/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.kN8EEwA50v/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-14 04:19:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.36M=0.001s 2017-06-14 04:19:39 (8.36 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122474 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:39.303043) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:39.303353) ========= 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.8yu64x1wJH/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.kN8EEwA50v/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.021684 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:39.352699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 04:19:39.353058) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev db144665b097fc65b282aa75ed9372fc164f2f51 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev db144665b097fc65b282aa75ed9372fc164f2f51 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8yu64x1wJH/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.kN8EEwA50v/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-14 04:19:39,423 truncating revision to first 12 chars 2017-06-14 04:19:39,423 Setting DEBUG logging. 2017-06-14 04:19:39,423 attempt 1/10 2017-06-14 04:19:39,423 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/db144665b097?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 04:19:40,905 unpacking tar archive at: mozilla-beta-db144665b097/testing/mozharness/ program finished with exit code 0 elapsedTime=1.740159 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 04:19:41.114566) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:41.117926) ========= script_repo_revision: db144665b097fc65b282aa75ed9372fc164f2f51 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:41.118357) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:41.118642) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 04:19:41.137926) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-14 04:19:41.138303) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8yu64x1wJH/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.kN8EEwA50v/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:19:41 INFO - MultiFileLogger online at 20170614 04:19:41 in /builds/slave/test 04:19:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:19:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:19:41 INFO - {'append_to_log': False, 04:19:41 INFO - 'base_work_dir': '/builds/slave/test', 04:19:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:19:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:19:41 INFO - 'branch': 'Mozilla-Beta', 04:19:41 INFO - 'buildbot_json_path': 'buildprops.json', 04:19:41 INFO - 'config_files': ('talos/mac_config.py',), 04:19:41 INFO - 'default_actions': ('clobber', 04:19:41 INFO - 'read-buildbot-config', 04:19:41 INFO - 'download-and-extract', 04:19:41 INFO - 'populate-webroot', 04:19:41 INFO - 'create-virtualenv', 04:19:41 INFO - 'install', 04:19:41 INFO - 'setup-mitmproxy', 04:19:41 INFO - 'run-tests'), 04:19:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:19:41 INFO - 'download_minidump_stackwalk': True, 04:19:41 INFO - 'download_symbols': 'ondemand', 04:19:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:19:41 INFO - 'tooltool.py': '/tools/tooltool.py', 04:19:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:19:41 INFO - '/tools/misc-python/virtualenv.py')}, 04:19:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:19:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:19:41 INFO - 'gecko_profile': False, 04:19:41 INFO - 'gecko_profile_interval': 0, 04:19:41 INFO - 'installer_path': 'installer.exe', 04:19:41 INFO - 'log_level': 'info', 04:19:41 INFO - 'log_name': 'talos', 04:19:41 INFO - 'log_to_console': True, 04:19:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:19:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:19:41 INFO - 'opt_config_files': (), 04:19:41 INFO - 'pip_index': False, 04:19:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:19:41 INFO - 'cmd': ('bash', 04:19:41 INFO - '-c', 04:19:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:19:41 INFO - 'enabled': True, 04:19:41 INFO - 'halt_on_failure': False, 04:19:41 INFO - 'name': 'check_screen_resolution'},), 04:19:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:19:41 INFO - 'cmd': ('bash', 04:19:41 INFO - '-c', 04:19:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:19:41 INFO - 'enabled': True, 04:19:41 INFO - 'halt_on_failure': False, 04:19:41 INFO - 'name': 'check_screen_resolution'},), 04:19:41 INFO - 'run_cmd_checks_enabled': True, 04:19:41 INFO - 'suite': 'dromaeojs-e10s', 04:19:41 INFO - 'system_bits': '32', 04:19:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:19:41 INFO - 'title': 't-yosemite-r7-0025', 04:19:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:19:41 INFO - 'use_talos_json': True, 04:19:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:19:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:19:41 INFO - 'work_dir': 'build'} 04:19:41 INFO - [mozharness: 2017-06-14 11:19:41.340189Z] Running clobber step. 04:19:41 INFO - Running pre-action listener: _resource_record_pre_action 04:19:41 INFO - Running main action method: clobber 04:19:41 INFO - rmtree: /builds/slave/test/build 04:19:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:19:44 INFO - Running post-action listener: _resource_record_post_action 04:19:44 INFO - [mozharness: 2017-06-14 11:19:44.918323Z] Finished clobber step (success) 04:19:44 INFO - [mozharness: 2017-06-14 11:19:44.918445Z] Running read-buildbot-config step. 04:19:44 INFO - Running pre-action listener: _resource_record_pre_action 04:19:44 INFO - Running main action method: read_buildbot_config 04:19:44 INFO - Using buildbot properties: 04:19:44 INFO - { 04:19:44 INFO - "project": "", 04:19:44 INFO - "product": "firefox", 04:19:44 INFO - "who": "ffxbld@noreply.mozilla.org", 04:19:44 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 04:19:44 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 04:19:44 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 04:19:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 04:19:44 INFO - "stage_platform": "macosx64", 04:19:44 INFO - "basedir": "/builds/slave/test", 04:19:44 INFO - "buildnumber": 4, 04:19:44 INFO - "platform": "yosemite_r7", 04:19:44 INFO - "parent_task_id": "NZFL2Dn7TfO8nA5WQB7pTw", 04:19:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:19:44 INFO - "slavebuilddir": "test", 04:19:44 INFO - "taskId": "MpU-Np1JSBuKQGa-Kk0myw", 04:19:44 INFO - "branch": "mozilla-beta", 04:19:44 INFO - "script_repo_revision": "production", 04:19:44 INFO - "revision": "db144665b097fc65b282aa75ed9372fc164f2f51", 04:19:44 INFO - "slavename": "t-yosemite-r7-0025", 04:19:44 INFO - "repo_path": "releases/mozilla-beta" 04:19:44 INFO - } 04:19:44 INFO - Finding installer, test and symbols from parent task. 04:19:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MpU-Np1JSBuKQGa-Kk0myw'}, attempt #1 04:19:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MpU-Np1JSBuKQGa-Kk0myw'}, attempt #1 04:19:46 INFO - Task dependencies: W1yldTiWTTWRAiwLtLwQtg NZFL2Dn7TfO8nA5WQB7pTw 04:19:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw'}, attempt #1 04:19:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:19:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/test_packages.json 04:19:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/target.crashreporter-symbols.zip 04:19:46 INFO - Running post-action listener: _resource_record_post_action 04:19:46 INFO - [mozharness: 2017-06-14 11:19:46.697289Z] Finished read-buildbot-config step (success) 04:19:46 INFO - [mozharness: 2017-06-14 11:19:46.697403Z] Running download-and-extract step. 04:19:46 INFO - Running pre-action listener: _resource_record_pre_action 04:19:46 INFO - Running main action method: download_and_extract 04:19:46 INFO - mkdir: /builds/slave/test/build/tests 04:19: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')]} 04:19:46 INFO - trying https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 04:19:46 INFO - Downloading https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 04:19:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 04:19:47 INFO - Downloaded 1290 bytes. 04:19:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 04:19:47 INFO - Using the following test package requirements: 04:19:47 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:19:47 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 04:19:47 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 04:19:47 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:19:47 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 04:19:47 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:19:47 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 04:19:47 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:19:47 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:19:47 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 04:19:47 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 04:19:47 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:19:47 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 04:19:47 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:19:47 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 04:19:47 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:19:47 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 04:19:47 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:19:47 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 04:19:47 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 04:19:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 04:19:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 04:19:47 INFO - Fetch https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 04:19:49 INFO - Content-Length response header: 58981020 04:19:49 INFO - Bytes received: 58981020 04:20:07 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:20:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 04:20:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 04:20:07 INFO - Fetch https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 04:20:09 INFO - Content-Length response header: 58981020 04:20:09 INFO - Bytes received: 58981020 04:20:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 04:20:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 04:20:27 INFO - Fetch https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 04:20:28 INFO - Content-Length response header: 14450018 04:20:28 INFO - Bytes received: 14450018 04:20: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')]} 04:20:28 INFO - trying https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:20:28 INFO - Downloading https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 04:20:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 04:20:30 INFO - Downloaded 51079085 bytes. 04:20:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:20:30 INFO - mkdir: /builds/slave/test/properties 04:20:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:20:30 INFO - Writing to file /builds/slave/test/properties/build_url 04:20:30 INFO - Contents: 04:20:30 INFO - build_url:https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:20:30 INFO - Running post-action listener: _resource_record_post_action 04:20:30 INFO - Running post-action listener: set_extra_try_arguments 04:20:30 INFO - [mozharness: 2017-06-14 11:20:30.434635Z] Finished download-and-extract step (success) 04:20:30 INFO - [mozharness: 2017-06-14 11:20:30.434772Z] Running populate-webroot step. 04:20:30 INFO - Running pre-action listener: _resource_record_pre_action 04:20:30 INFO - Running main action method: populate_webroot 04:20:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:20:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:20:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:20:30 INFO - '--tppagecycles', 04:20:30 INFO - '7'], 04:20:30 INFO - 'tests': ['tsvgm']}}, 04:20:30 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:20:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:20:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:20:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:20:30 INFO - 'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:20:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:20:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:20:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:20:30 INFO - 'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['damp', 'tps']}, 04:20:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:20:30 INFO - 'tests': ['damp', 'tps']}, 04:20:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['dromaeo_dom']}, 04:20:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:20:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:20:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:20:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:20:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:20:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['a11yr', 04:20:30 INFO - 'ts_paint', 04:20:30 INFO - 'tpaint', 04:20:30 INFO - 'sessionrestore', 04:20:30 INFO - 'sessionrestore_no_auto_restore', 04:20:30 INFO - 'tabpaint']}, 04:20:30 INFO - 'other-e10s': {'tests': ['a11yr', 04:20:30 INFO - 'ts_paint', 04:20:30 INFO - 'tpaint', 04:20:30 INFO - 'sessionrestore', 04:20:30 INFO - 'sessionrestore_no_auto_restore', 04:20:30 INFO - 'tabpaint']}, 04:20:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:20:30 INFO - 'ts_paint', 04:20:30 INFO - 'tpaint', 04:20:30 INFO - 'sessionrestore', 04:20:30 INFO - 'sessionrestore_no_auto_restore', 04:20:30 INFO - 'tabpaint']}, 04:20:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:20:30 INFO - 'ts_paint', 04:20:30 INFO - 'tpaint', 04:20:30 INFO - 'sessionrestore', 04:20:30 INFO - 'sessionrestore_no_auto_restore', 04:20:30 INFO - 'tabpaint']}, 04:20:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['a11yr', 04:20:30 INFO - 'ts_paint', 04:20:30 INFO - 'tpaint', 04:20:30 INFO - 'sessionrestore', 04:20:30 INFO - 'sessionrestore_no_auto_restore', 04:20:30 INFO - 'tabpaint']}, 04:20:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['a11yr', 04:20:30 INFO - 'ts_paint', 04:20:30 INFO - 'tpaint', 04:20:30 INFO - 'sessionrestore', 04:20:30 INFO - 'sessionrestore_no_auto_restore', 04:20:30 INFO - 'tabpaint']}, 04:20:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:20:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:20:30 INFO - 'bloom_basic_ref']}, 04:20:30 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:20:30 INFO - 'talos_options': ['--mitmproxy', 04:20:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:20:30 INFO - '--firstNonBlankPaint'], 04:20:30 INFO - 'tests': ['quantum_pageload_google', 04:20:30 INFO - 'quantum_pageload_youtube', 04:20:30 INFO - 'quantum_pageload_amazon', 04:20:30 INFO - 'quantum_pageload_facebook']}, 04:20:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['tsvgx', 04:20:30 INFO - 'tsvgr_opacity', 04:20:30 INFO - 'tart', 04:20:30 INFO - 'tscrollx', 04:20:30 INFO - 'cart', 04:20:30 INFO - 'tsvg_static']}, 04:20:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:20:30 INFO - 'tsvgr_opacity', 04:20:30 INFO - 'tart', 04:20:30 INFO - 'tscrollx', 04:20:30 INFO - 'cart', 04:20:30 INFO - 'tsvg_static']}, 04:20:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:20:30 INFO - 'talos_options': ['--disable-e10s'], 04:20:30 INFO - 'tests': ['tp5o']}, 04:20:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:20:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:20:30 INFO - 'talos_options': ['--disable-e10s', 04:20:30 INFO - '--xperf_path', 04:20:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:20:30 INFO - 'tests': ['tp5n']}, 04:20:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:20:30 INFO - 'talos_options': ['--xperf_path', 04:20:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:20:30 INFO - 'tests': ['tp5n']}}, 04:20:30 INFO - 'talos.zip': {'path': '', 04:20:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:20:30 INFO - Running post-action listener: _resource_record_post_action 04:20:30 INFO - [mozharness: 2017-06-14 11:20:30.459617Z] Finished populate-webroot step (success) 04:20:30 INFO - [mozharness: 2017-06-14 11:20:30.459764Z] Running create-virtualenv step. 04:20:30 INFO - Running pre-action listener: _resource_record_pre_action 04:20:30 INFO - Running main action method: create_virtualenv 04:20:30 INFO - Creating virtualenv /builds/slave/test/build/venv 04:20: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 04:20:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:20:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:20:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:20:30 INFO - Using real prefix '/tools/python27' 04:20:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:20:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:20:34 INFO - Installing pip.................done. 04:20:34 INFO - Return code: 0 04:20:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:20:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:20:34 INFO - Reading from file tmpfile_stdout 04:20:34 INFO - Output received: 04:20: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) 04:20:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:20: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')]} 04:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20: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')]} 04:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20: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 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20: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 04:20: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 04:20:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:34 INFO - 'HOME': '/Users/cltbld', 04:20:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:34 INFO - 'LOGNAME': 'cltbld', 04:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:34 INFO - 'MOZ_NO_REMOTE': '1', 04:20:34 INFO - 'NO_EM_RESTART': '1', 04:20:34 INFO - 'PAGER': '/bin/cat', 04:20:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:34 INFO - 'PWD': '/builds/slave/test', 04:20:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:34 INFO - 'SHELL': '/bin/bash', 04:20:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:34 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:34 INFO - 'USER': 'cltbld', 04:20:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:34 INFO - 'XPC_FLAGS': '0x0', 04:20:34 INFO - 'XPC_SERVICE_NAME': '0', 04:20:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:20:35 INFO - Cleaning up... 04:20:35 INFO - Return code: 0 04:20:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20: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 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20: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 04:20: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 04:20:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:35 INFO - 'HOME': '/Users/cltbld', 04:20:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:35 INFO - 'LOGNAME': 'cltbld', 04:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:35 INFO - 'MOZ_NO_REMOTE': '1', 04:20:35 INFO - 'NO_EM_RESTART': '1', 04:20:35 INFO - 'PAGER': '/bin/cat', 04:20:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:35 INFO - 'PWD': '/builds/slave/test', 04:20:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:35 INFO - 'SHELL': '/bin/bash', 04:20:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:35 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:35 INFO - 'USER': 'cltbld', 04:20:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:35 INFO - 'XPC_FLAGS': '0x0', 04:20:35 INFO - 'XPC_SERVICE_NAME': '0', 04:20:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:35 INFO - Downloading/unpacking psutil>=3.1.1 04:20: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 04:20: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 04:20: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 04:20: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 04:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:20:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:20:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:20:38 INFO - Installing collected packages: psutil 04:20:38 INFO - Running setup.py install for psutil 04:20:38 INFO - building 'psutil._psutil_osx' extension 04:20: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:20: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 04:20: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 04:20: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 04:20:38 INFO - building 'psutil._psutil_posix' extension 04:20: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 04:20:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:20:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:20:38 INFO - ^ 04:20:38 INFO - 1 warning generated. 04:20: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 04:20:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:20:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:20:39 INFO - Successfully installed psutil 04:20:39 INFO - Cleaning up... 04:20:39 INFO - Return code: 0 04:20:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:39 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 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:39 INFO - 'HOME': '/Users/cltbld', 04:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:39 INFO - 'LOGNAME': 'cltbld', 04:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:39 INFO - 'MOZ_NO_REMOTE': '1', 04:20:39 INFO - 'NO_EM_RESTART': '1', 04:20:39 INFO - 'PAGER': '/bin/cat', 04:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:39 INFO - 'PWD': '/builds/slave/test', 04:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:39 INFO - 'SHELL': '/bin/bash', 04:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:39 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:39 INFO - 'USER': 'cltbld', 04:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:39 INFO - 'XPC_FLAGS': '0x0', 04:20:39 INFO - 'XPC_SERVICE_NAME': '0', 04:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:20: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 04:20: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 04:20: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 04:20: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 04:20:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:20:40 INFO - Installing collected packages: mozsystemmonitor 04:20:40 INFO - Running setup.py install for mozsystemmonitor 04:20:41 INFO - Successfully installed mozsystemmonitor 04:20:41 INFO - Cleaning up... 04:20:41 INFO - Return code: 0 04:20:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:41 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 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:20:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:41 INFO - 'HOME': '/Users/cltbld', 04:20:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:41 INFO - 'LOGNAME': 'cltbld', 04:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:41 INFO - 'MOZ_NO_REMOTE': '1', 04:20:41 INFO - 'NO_EM_RESTART': '1', 04:20:41 INFO - 'PAGER': '/bin/cat', 04:20:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:41 INFO - 'PWD': '/builds/slave/test', 04:20:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:41 INFO - 'SHELL': '/bin/bash', 04:20:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:41 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:41 INFO - 'USER': 'cltbld', 04:20:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:41 INFO - 'XPC_FLAGS': '0x0', 04:20:41 INFO - 'XPC_SERVICE_NAME': '0', 04:20:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:41 INFO - Downloading/unpacking jsonschema==2.5.1 04:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:20:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:20: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 04:20: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 04:20: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 04:20: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 04:20:43 INFO - Downloading functools32-3.2.3-2.tar.gz 04:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:20:43 INFO - warning: no files found matching '*.txt' 04:20:43 INFO - no previously-included directories found matching 'build' 04:20:43 INFO - no previously-included directories found matching 'dist' 04:20:43 INFO - no previously-included directories found matching '.git*' 04:20:43 INFO - Installing collected packages: jsonschema, functools32 04:20:43 INFO - Running setup.py install for functools32 04:20:43 INFO - warning: no files found matching '*.txt' 04:20:43 INFO - no previously-included directories found matching 'build' 04:20:43 INFO - no previously-included directories found matching 'dist' 04:20:43 INFO - no previously-included directories found matching '.git*' 04:20:43 INFO - Successfully installed jsonschema functools32 04:20:43 INFO - Cleaning up... 04:20:43 INFO - Return code: 0 04:20:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20: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', '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 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:20: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 functools32==3.2.3-2 04:20:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:43 INFO - 'HOME': '/Users/cltbld', 04:20:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:43 INFO - 'LOGNAME': 'cltbld', 04:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:43 INFO - 'MOZ_NO_REMOTE': '1', 04:20:43 INFO - 'NO_EM_RESTART': '1', 04:20:43 INFO - 'PAGER': '/bin/cat', 04:20:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:43 INFO - 'PWD': '/builds/slave/test', 04:20:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:43 INFO - 'SHELL': '/bin/bash', 04:20:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:43 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:43 INFO - 'USER': 'cltbld', 04:20:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:43 INFO - 'XPC_FLAGS': '0x0', 04:20:43 INFO - 'XPC_SERVICE_NAME': '0', 04:20:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:20:43 INFO - Cleaning up... 04:20:43 INFO - Return code: 0 04:20:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20: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 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20: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 04:20: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 04:20:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:43 INFO - 'HOME': '/Users/cltbld', 04:20:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:43 INFO - 'LOGNAME': 'cltbld', 04:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:43 INFO - 'MOZ_NO_REMOTE': '1', 04:20:43 INFO - 'NO_EM_RESTART': '1', 04:20:43 INFO - 'PAGER': '/bin/cat', 04:20:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:43 INFO - 'PWD': '/builds/slave/test', 04:20:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:43 INFO - 'SHELL': '/bin/bash', 04:20:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:43 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:43 INFO - 'USER': 'cltbld', 04:20:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:43 INFO - 'XPC_FLAGS': '0x0', 04:20:43 INFO - 'XPC_SERVICE_NAME': '0', 04:20:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:44 INFO - Downloading/unpacking blobuploader==1.2.4 04:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:45 INFO - Downloading blobuploader-1.2.4.tar.gz 04:20:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:20:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:20: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 04:20: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 04:20: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 04:20: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 04:20:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:20:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:46 INFO - Downloading docopt-0.6.1.tar.gz 04:20:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:20:46 INFO - Installing collected packages: blobuploader, requests, docopt 04:20:46 INFO - Running setup.py install for blobuploader 04:20:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:20:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:20:46 INFO - Running setup.py install for requests 04:20:47 INFO - Running setup.py install for docopt 04:20:47 INFO - Successfully installed blobuploader requests docopt 04:20:47 INFO - Cleaning up... 04:20:47 INFO - Return code: 0 04:20:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:47 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 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:20:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:47 INFO - 'HOME': '/Users/cltbld', 04:20:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:47 INFO - 'LOGNAME': 'cltbld', 04:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:47 INFO - 'MOZ_NO_REMOTE': '1', 04:20:47 INFO - 'NO_EM_RESTART': '1', 04:20:47 INFO - 'PAGER': '/bin/cat', 04:20:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:47 INFO - 'PWD': '/builds/slave/test', 04:20:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:47 INFO - 'SHELL': '/bin/bash', 04:20:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:47 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:47 INFO - 'USER': 'cltbld', 04:20:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:47 INFO - 'XPC_FLAGS': '0x0', 04:20:47 INFO - 'XPC_SERVICE_NAME': '0', 04:20:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-xJtir7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-z_AhrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-P_2LNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-QhzLcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-AatDQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-9Ctuv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-UoXaSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-D6sE1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-mJ1OWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-uTXVSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-DW7la9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-82lBP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-xv1qbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-DI0cT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-WcsepP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-yG9DN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:49 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-NR9cpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:20:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:20:49 INFO - Running setup.py install for manifestparser 04:20:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:20:49 INFO - Running setup.py install for mozcrash 04:20:50 INFO - Running setup.py install for mozdebug 04:20:50 INFO - Running setup.py install for mozdevice 04:20:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:20:50 INFO - Running setup.py install for mozfile 04:20:50 INFO - Running setup.py install for mozhttpd 04:20:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:20:50 INFO - Running setup.py install for mozinfo 04:20:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:20:50 INFO - Running setup.py install for mozInstall 04:20:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:20:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:20:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:20:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:20:50 INFO - Running setup.py install for mozleak 04:20:51 INFO - Running setup.py install for mozlog 04:20:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:20:51 INFO - Running setup.py install for moznetwork 04:20:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:20:51 INFO - Running setup.py install for mozprocess 04:20:51 INFO - Running setup.py install for mozprofile 04:20:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:20:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:20:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:20:51 INFO - Running setup.py install for mozrunner 04:20:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:20:51 INFO - Running setup.py install for mozscreenshot 04:20:52 INFO - Running setup.py install for moztest 04:20:52 INFO - Running setup.py install for mozversion 04:20:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:20:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:20:52 INFO - Cleaning up... 04:20:52 INFO - Return code: 0 04:20:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:52 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 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:52 INFO - 'HOME': '/Users/cltbld', 04:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:52 INFO - 'LOGNAME': 'cltbld', 04:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:52 INFO - 'MOZ_NO_REMOTE': '1', 04:20:52 INFO - 'NO_EM_RESTART': '1', 04:20:52 INFO - 'PAGER': '/bin/cat', 04:20:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:52 INFO - 'PWD': '/builds/slave/test', 04:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:52 INFO - 'SHELL': '/bin/bash', 04:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:52 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:52 INFO - 'USER': 'cltbld', 04:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:52 INFO - 'XPC_FLAGS': '0x0', 04:20:52 INFO - 'XPC_SERVICE_NAME': '0', 04:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-jCSBN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-cFlK7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-GftH83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Azr4eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:53 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)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0Nzwpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tl78Lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-IbdB_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:53 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)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-sODm1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:53 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)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0lzzQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-liIu3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-bmoNSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-dnwK3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2ZUWmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-584Mt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-4K_M1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-x5mJNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-hwf50M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:20:54 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)) 04:20:54 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)) 04:20:54 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)) 04:20:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:57 INFO - Downloading blessings-1.6.tar.gz 04:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:20:57 INFO - Installing collected packages: blessings 04:20:57 INFO - Running setup.py install for blessings 04:20:57 INFO - Successfully installed blessings 04:20:57 INFO - Cleaning up... 04:20:57 INFO - Return code: 0 04:20:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:20:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:20:57 INFO - Reading from file tmpfile_stdout 04:20:57 INFO - Current package versions: 04:20:57 INFO - blessings == 1.6 04:20:57 INFO - blobuploader == 1.2.4 04:20:57 INFO - docopt == 0.6.1 04:20:57 INFO - functools32 == 3.2.3-2 04:20:57 INFO - jsonschema == 2.5.1 04:20:57 INFO - manifestparser == 1.1 04:20:57 INFO - mozInstall == 1.13 04:20:57 INFO - mozcrash == 1.0 04:20:57 INFO - mozdebug == 0.1 04:20:57 INFO - mozdevice == 0.51 04:20:57 INFO - mozfile == 1.2 04:20:57 INFO - mozhttpd == 0.7 04:20:57 INFO - mozinfo == 0.10 04:20:57 INFO - mozleak == 0.1 04:20:57 INFO - mozlog == 3.4 04:20:57 INFO - moznetwork == 0.27 04:20:57 INFO - mozprocess == 0.25 04:20:57 INFO - mozprofile == 0.28 04:20:57 INFO - mozrunner == 6.13 04:20:57 INFO - mozscreenshot == 0.1 04:20:57 INFO - mozsystemmonitor == 0.3 04:20:57 INFO - moztest == 0.8 04:20:57 INFO - mozversion == 1.4 04:20:57 INFO - psutil == 3.1.1 04:20:57 INFO - requests == 1.2.3 04:20:57 INFO - wsgiref == 0.1.2 04:20:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:20:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:57 INFO - 'HOME': '/Users/cltbld', 04:20:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:57 INFO - 'LOGNAME': 'cltbld', 04:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:57 INFO - 'MOZ_NO_REMOTE': '1', 04:20:57 INFO - 'NO_EM_RESTART': '1', 04:20:57 INFO - 'PAGER': '/bin/cat', 04:20:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:57 INFO - 'PWD': '/builds/slave/test', 04:20:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:57 INFO - 'SHELL': '/bin/bash', 04:20:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:57 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:57 INFO - 'USER': 'cltbld', 04:20:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:57 INFO - 'XPC_FLAGS': '0x0', 04:20:57 INFO - 'XPC_SERVICE_NAME': '0', 04:20:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:20:58 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:59 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:20:59 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)) 04:20:59 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)) 04:20:59 INFO - Installing collected packages: marionette-driver 04:20:59 INFO - Running setup.py install for marionette-driver 04:20:59 INFO - Successfully installed marionette-driver 04:20:59 INFO - Cleaning up... 04:20:59 INFO - Return code: 0 04:20:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d30c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd12d7dc40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, '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.8yu64x1wJH/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.kN8EEwA50v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:20:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:59 INFO - 'HOME': '/Users/cltbld', 04:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:59 INFO - 'LOGNAME': 'cltbld', 04:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:59 INFO - 'MOZ_NO_REMOTE': '1', 04:20:59 INFO - 'NO_EM_RESTART': '1', 04:20:59 INFO - 'PAGER': '/bin/cat', 04:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:59 INFO - 'PWD': '/builds/slave/test', 04:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:59 INFO - 'SHELL': '/bin/bash', 04:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:20:59 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:59 INFO - 'USER': 'cltbld', 04:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:59 INFO - 'XPC_FLAGS': '0x0', 04:20:59 INFO - 'XPC_SERVICE_NAME': '0', 04:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:21:00 INFO - Cleaning up... 04:21:00 INFO - Return code: 0 04:21:00 INFO - Running post-action listener: _resource_record_post_action 04:21:00 INFO - Running post-action listener: _start_resource_monitoring 04:21:00 INFO - Starting resource monitoring. 04:21:00 INFO - [mozharness: 2017-06-14 11:21:00.176319Z] Finished create-virtualenv step (success) 04:21:00 INFO - [mozharness: 2017-06-14 11:21:00.177404Z] Running install step. 04:21:00 INFO - Running pre-action listener: _resource_record_pre_action 04:21:00 INFO - Running main action method: install 04:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:00 INFO - Reading from file tmpfile_stdout 04:21:00 INFO - Detecting whether we're running mozinstall >=1.0... 04:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:21:00 INFO - Reading from file tmpfile_stdout 04:21:00 INFO - Output received: 04:21:00 INFO - Usage: mozinstall [options] installer 04:21:00 INFO - Options: 04:21:00 INFO - -h, --help show this help message and exit 04:21:00 INFO - -d DEST, --destination=DEST 04:21:00 INFO - Directory to install application into. [default: 04:21:00 INFO - "/builds/slave/test"] 04:21:00 INFO - --app=APP Application being installed. [default: firefox] 04:21:00 INFO - mkdir: /builds/slave/test/build/application 04:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:21:16 INFO - Reading from file tmpfile_stdout 04:21:16 INFO - Output received: 04:21:16 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:21:16 INFO - Running post-action listener: _resource_record_post_action 04:21:16 INFO - [mozharness: 2017-06-14 11:21:16.903446Z] Finished install step (success) 04:21:16 INFO - [mozharness: 2017-06-14 11:21:16.903608Z] Running setup-mitmproxy step. 04:21:16 INFO - Running pre-action listener: _resource_record_pre_action 04:21:16 INFO - Running main action method: setup_mitmproxy 04:21:16 INFO - Skipping: mitmproxy is not required 04:21:16 INFO - Running post-action listener: _resource_record_post_action 04:21:16 INFO - [mozharness: 2017-06-14 11:21:16.904365Z] Finished setup-mitmproxy step (success) 04:21:16 INFO - [mozharness: 2017-06-14 11:21:16.904509Z] Running run-tests step. 04:21:16 INFO - Running pre-action listener: _resource_record_pre_action 04:21:16 INFO - Running main action method: run_tests 04:21:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:21:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:21:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:21:16 INFO - 2017-06-14 04:21:16.919 screenresolution[1881:8380] starting screenresolution argv=screenresolution get 04:21:16 INFO - 2017-06-14 04:21:16.921 screenresolution[1881:8380] Display 0: 1600x1200x32@60 04:21:16 INFO - 2017-06-14 04:21:16.927 screenresolution[1882:8382] starting screenresolution argv=screenresolution list 04:21:16 INFO - Available Modes on Display 0 04:21:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:21:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:21:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:21:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:21:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:21:17 INFO - Intel Iris: 04:21:17 INFO - Chipset Model: Intel Iris 04:21:17 INFO - Type: GPU 04:21:17 INFO - Bus: Built-In 04:21:17 INFO - VRAM (Dynamic, Max): 1536 MB 04:21:17 INFO - Vendor: Intel (0x8086) 04:21:17 INFO - Device ID: 0x0a2e 04:21:17 INFO - Revision ID: 0x0009 04:21:17 INFO - Displays: 04:21:17 INFO - Display: 04:21:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:21:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:21:17 INFO - Display Serial Number: 1600x1200 60 04:21:17 INFO - Main Display: Yes 04:21:17 INFO - Mirror: Off 04:21:17 INFO - Online: Yes 04:21:17 INFO - Rotation: Supported 04:21:17 INFO - Return code: 0 04:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:21:17 INFO - Python 2.7.3 04:21:17 INFO - Return code: 0 04:21:17 INFO - grabbing minidump binary from tooltool 04:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:17 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 0x109a86d30>, '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 0x109a50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a77be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:21:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:21:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:21:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:21:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:21:17 INFO - Return code: 0 04:21:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:21:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:21:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:21:17 INFO - ENV: RUST_BACKTRACE is now 1 04:21:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:21:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8yu64x1wJH/Render', 04:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:17 INFO - 'HOME': '/Users/cltbld', 04:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:17 INFO - 'LOGNAME': 'cltbld', 04:21:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:21:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:17 INFO - 'MOZ_NO_REMOTE': '1', 04:21:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:21:17 INFO - 'NO_EM_RESTART': '1', 04:21:17 INFO - 'PAGER': '/bin/cat', 04:21:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:17 INFO - 'PWD': '/builds/slave/test', 04:21:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:17 INFO - 'RUST_BACKTRACE': '1', 04:21:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:21:17 INFO - 'SHELL': '/bin/bash', 04:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kN8EEwA50v/Listeners', 04:21:17 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 04:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:17 INFO - 'USER': 'cltbld', 04:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:17 INFO - 'XPC_FLAGS': '0x0', 04:21:17 INFO - 'XPC_SERVICE_NAME': '0', 04:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:21:17 INFO - mozversion application_buildid: 20170614030356 04:21:17 INFO - mozversion application_changeset: db144665b097fc65b282aa75ed9372fc164f2f51 04:21:17 INFO - mozversion application_display_name: Firefox 04:21:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:21:17 INFO - mozversion application_name: Firefox 04:21:17 INFO - mozversion application_remotingname: firefox 04:21:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:21:17 INFO - mozversion application_vendor: Mozilla 04:21:17 INFO - mozversion application_version: 55.0 04:21:17 INFO - mozversion platform_buildid: 20170614030356 04:21:17 INFO - mozversion platform_changeset: db144665b097fc65b282aa75ed9372fc164f2f51 04:21:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:21:17 INFO - mozversion platform_version: 55.0 04:21:17 INFO - using testdate: 1497439277 04:21:17 INFO - actual date: 1497439277 04:21:17 INFO - starting webserver on 'localhost:49300' 04:21:17 INFO - SUITE-START | Running 2 tests 04:21:17 INFO - TEST-START | dromaeo_css 04:21:17 INFO - operating with platform_type : mac_ 04:21:17 INFO - Initialising browser for dromaeo_css test... 04:21:17 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49300/getInfo.html -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp_swplw/profile 04:21:17 INFO - TEST-INFO | started process 1892 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49300/getInfo.html -foreground) 04:21:50 INFO - TEST-INFO | 1892: killed by SIGTERM 04:21:50 INFO - Browser initialized. 04:21:50 INFO - Running cycle 1/1 for dromaeo_css test... 04:21:50 INFO - TEST-INFO | started process 1897 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp_swplw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:21:52 INFO - PID 1897 | 2017-06-14 04:21:52.020 plugin-container[1898:8786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 04:21:52 INFO - PID 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:21:52 INFO - PID 1897 | 2017-06-14 04:21:52.023 plugin-container[1898:8786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 04:21:52 INFO - PID 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:21:52 INFO - PID 1897 | 2017-06-14 04:21:52.023 plugin-container[1898:8786] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:22:02 INFO - PID 1897 | RSS: Main: 184053760 04:22:02 INFO - PID 1897 | 04:25:04 INFO - PID 1897 | Cycle 1(1): loaded http://localhost:49300/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49300/tests/dromaeo/cssquery-ext.html) 04:25:07 INFO - PID 1897 | RSS: Main: 206090240 04:25:07 INFO - PID 1897 | 04:28:08 INFO - PID 1897 | Cycle 1(1): loaded http://localhost:49300/tests/dromaeo/cssquery-ext.html (next: http://localhost:49300/tests/dromaeo/cssquery-jquery.html) 04:28:09 INFO - PID 1897 | RSS: Main: 212828160 04:28:09 INFO - PID 1897 | 04:31:11 INFO - PID 1897 | Cycle 1(1): loaded http://localhost:49300/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49300/tests/dromaeo/cssquery-mootools.html) 04:31:11 INFO - PID 1897 | RSS: Main: 216883200 04:31:11 INFO - PID 1897 | 04:34:13 INFO - PID 1897 | Cycle 1(1): loaded http://localhost:49300/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49300/tests/dromaeo/cssquery-prototype.html) 04:34:14 INFO - PID 1897 | RSS: Main: 216211456 04:34:14 INFO - PID 1897 | 04:37:16 INFO - PID 1897 | Cycle 1(1): loaded http://localhost:49300/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49300/tests/dromaeo/cssquery-yui.html) 04:37:17 INFO - PID 1897 | RSS: Main: 216825856 04:37:17 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | Cycle 1(1): loaded http://localhost:49300/tests/dromaeo/cssquery-yui.html (next: http://localhost:49300/tests/dromaeo/cssquery-dojo.html) 04:40:19 INFO - PID 1897 | RSS: Main: 214646784 04:40:19 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | __start_tp_report 04:40:19 INFO - PID 1897 | _x_x_mozilla_page_load 04:40:19 INFO - PID 1897 | _x_x_mozilla_page_load_details 04:40:19 INFO - PID 1897 | |i|pagename|runs| 04:40:19 INFO - PID 1897 | |0;dojo.html;3702.9444558331647;3829.7127715421343;3831.2146010067922;3864.1885204107143;3881.0879443330814;6164.660943648098;6220.222472190976;6245.625262484243;6315.8105256842255;6445.129907462491;5649.830505084854;6130.551011451152;6192.938070619292;6246.968765156179;6360.427561519462;5371.57027437805;5486.1222204447295;5560.527355174815;5665.348484924225;5777.104548794922;5445.53712934399;5512.91730624041;5579.246801681782;5652.434756524356;5697.1169468732205;5271.789128434858;5330.333708286465;5450.91823622646;5543.972280138614;5605.2713147291;5468.070428027245;5520.944790552124;5548.44515548446;5634.492895639412;5657.094864821609;5343.465653434664;5460.754266058037;5534.695591742453;5536.612437129403;5638.548916086745;6091.695415229221;6150.7232174552255;6163.260408750979;6243.125962365272;6339.904901426482;5610.663360198401;6564.310747371505;6701.698423570951;6736.797896063126;6878.793636190922;4666.556677115668;6684.699188536528;6807.761728339485;6846.315368463163;6855.143107111611;6238.754048542521;6277.466415354746;6318.968405157991;6488.351164883521;6511.902321465182;6943.062686537354;7091.999999999897;7128.607926563985;7146.821329466805;7152.785416437515;6440.677966101676;6455.870882582322;6568.507361947873;6596.472282217411;6631.469482441393;5620.269364982526;6779.49153813466;6892.931070689329;6923.169219693669;6985.301469853076;6576.408123268929;6697.665116744143;6804.149481314836;6844.041834143224;6865.725370985205;6675.465962723068;6683.465322774166;6745.898811517831;6770.627615481098;6920.792376228721;4647.790849411785;5340.305760251143;5457.672539647634;5467.617266791286;5472.835814925558;4609.654275929319;4625.722456652612;4669.4630117536235;4700.341952126705;4756.6908150970075;6137.263528376534;6142.942703134429;6281.246250449975;6361.9045714314325;6485;5528.668279903219;6839.1451068616425;6963.582185068912;6996.6851491683;7000.26497217795;6363.872722545523;6366.490680745622;6381.202349706286;6510.674466276762;6587.736490540325;5861.970690146607;5887.293524777054;5951.434613711691;6009.098635204754;6052.66710330936;6665.633390163587;6820.488463365366;7013.509054366247;7070.681819318246;7099.929000709927;6658.467322614276;6669.266380698216;6695.564788288941;6835.521513494206;6837.555558888656;6820.283870193558;7011.088558487364;7017.578945263301;7134.429245660231;7145.1783044948415;6653.534252602271;6655.900161497679;6732.730690772314;6762.49281303914;6863.450923925974;5470.53500452466;6770.627615481196;6796.932030679828;6844.623545705034;6971.2331643518155;6531.248906375828;6599.53803233769;6657.933420665731;6744.190697116377;6751.831204219738;5425.918611220756;6453;6453.022867701275;6502.284748677547;6673.699683514351;6313.179286692701;6480.967595161994;6616.768413105518;6676.198856137295;6692.364225398579;6616.371444712745;6638.53530252897;6765.985102234507;6856.691448884712;6860.725570977105;5256.710880901587;6279.905801412891;6304.873902522016;6444.258910225197;6452.580582262326 04:40:19 INFO - PID 1897 | |1;ext.html;706.6007705646309;787.1577412168978;817.219555324665;880.788610733424;891.4116682989225;41364.79317603415;42449.36325955113;43496.565034349645;43652.781736091354;43889.56110438899;42879;43375.78312108435;43416.56583434172;43705.56294437055;43786;7561.054868141482;7801.609919504031;7914.722984695537;7946.205379462036;8233.135520770293;8423.36824738144;8484.36367272454;8541.615627296755;8659.95670021649;8671.739847804576;7523;8343.83312333756;8598.441101328424;8661.826763464727;8699.869501957475;8418.284445822112;8419.70531031414;8629.525376104311;8657.870131948026;8682.826343473094;8262.586870656443;8308.418410711252;8467.195616416431;8488.236058754741;8516;31808.522872156937;38458.42312365317;39616.60383396158;39848.40227396562;39971.80014099911;3607.2244467439627;3683.3001729671278;3686.2627474505207;3689.5203623529037;3739.607341229159;30886;31921.680783192103;31995.360092798015;32284.708611655504;32316.838415808004;3058;3143.984280078585;3189.409959157563;3219.2917558137174;3245.594300712411;260535;268789;273617.999999998;320061.3996930001;326866;43081.56918430776;50148.74925625422;50945.23582146271;50961;51747;41213.58786412098;53547.732261338446;54108.188377174374;54117.72941135269;54318.18522722162;15699.607509812118;15843.207839607974;15939.44211952576;16099.275532601061;16101.275442604876;12894.613161605166;12994.870051299555;13088.738225235442;13131.212127272394;13338.399772010283;13898.58304250874;14022.649433764238;14129.222892740792;14168.149911005372;14330.57008289753;5144.765256338452;5829.970850145807;5895.64626122434;5979.983402821531;6036.366181550961;1007.2244371833647;1021.2851004297022;1022.892596277395;1040.105509262034;1083.913286937043;20375.592488150152;22804.517706349034;23920.7607923922;24182.075396508902;25666.358341041625;1127.9492422840992;1139.8347239650204;1165.539611853313;1173.1904985559936;1197.6886009637383;9440.811183776286;11775.293482391084;12521.248725076342;12671.36643167838;12907;315457;315466;316533;323060;327490;57998.71000645054;58020;58106.41893581094;58203;58241.41758582445;1527.5722797616686;1533.8389469105807;1555.9922200388926;1575.330484544087;1592.8805339599576;23149.189778357675;23183.76816231816;23725;23861.642075368538;24704.8882800278;40187;40730.9817254571;41358.586414135476;41389;41585.58414415817;271869;276330;280124;281440;281728;27775.30561735973;30251;35047.824760876036;35360.11599710028;36176.8191159053;43799.56200438082;45129.0974180508;46114.538854611026;47025;47085.587432377084;27598.310042249104;29943.951961681236;30982;31855.84072079625;32011.679883200868;25084.122055727963;30710.539341910335;30782.538261926537;31374.372512549166;32197;36780.81609592042;37820;38201.808990954865;38293;38691.41962870616;22024.559508810056;26163.73836261613;27507.31231719223;31899.84050079735;32364.35271294608;18962;19137.425877223704;21074;24207.668578228368;24694.629580555946 04:40:19 INFO - PID 1897 | |2;jquery.html;454.9909001819964;527.5647590737641;529.7086602368696;531.6836482293035;534.3320848938827;22458.663120053192;23075.76924230757;23742.931568079457;23799;24996.50006999859;22534.985925633344;22946.77053229467;23617.763822361772;24796.76016199192;25338.873305633493;6366.840828979278;6403.263624683151;6572.441342485894;6614.8677026459445;6640.867182656344;5263;6097.756089756404;6257.092721555372;6292.276388215345;6453.419192272694;6268.749250030016;6366.872662546746;6423.229212494484;6451.741930322805;6490.707918143671;6302.968485157568;6463.515236357292;6471.546991710606;6473.352664733536;6491.513136514756;5734.340550836666;5840.094785308284;5910.290765108214;5950.672713000783;5951.970240148771;28961.986330478543;29388.559171612444;29732.554011689626;29739;30745.846270768503;2223.7776222377793;2311.5492478966607;2314.4561028158537;2346.7887890089805;2388.7133543974833;19352.70970935437;19624.822510649406;19668.50828729279;19850.503737406536;19874.403767886986;2147.001644235422;2196.352076137527;2224.877631730254;2233.3076746208567;2234.698315727372;163503;167654;173636.13181934136;175462.99999999872;200129;13742.244176570384;14948.925255373872;15332.257210109046;15694.199123831248;15720.60698482547;20858.37424877256;21013.47466313324;21084.789152108282;21494.89252553727;21509.247176348752;10044.447555384524;10205.438700871375;10209.876913539505;10490.737731556772;10592.947035264775;7558.433117516099;9609.135177834029;9746.976567460457;9932;10013.749656258651;9182.77043073915;9214.90785092154;9363.90636093644;9378.5779639915;9412.858807117898;2206.7682893295973;2329.6738456616085;2607.556715358394;2608.2696844883462;2667.1731763153484;922.6586163119555;923.5982347678781;941.3504681769557;999.0359303272377;999.2855108597388;17183.3985810496;20146.597068058556;20828.375148745257;21537.077303741597;22236.110555577594;978.2076518020426;983.1593987141034;1012.1143999000874;1016.2327442780653;1056.804491169136;8881.156290152425;10680.252382333161;10771.515281812337;10935.343879367263;11253.324800511995;510899;525794;527371;530518;551171;141346.29326853505;144708.27645861913;154221;159703;172619;1055.714956961616;1087.4127970895786;1109.7003808971533;1214.7084699672016;1286.7876800328215;21439.67840482363;21984.560308793414;23314.883425582764;23386.883065584563;23562.646560301266;47274.527254727014;67854.66072669605;69066.61866762736;69432;70107;75355.73932782025;90836;91054.08945910457;93974.06025939653;94342;11765.823512647146;11811.940940295588;11849.992750616295;11879.930806227481;11917.22538035025;17808;17956.730649040015;18267.086645667398;18294.634107318045;18624.906875465535;14389.352479138674;14744.483943061941;15093;15266;15311.540653780405;12655.493780249055;13068.673283167996;13133.015023873439;13275.402606882906;13675.24786136772;14765.852341476448;15096;15164;15185.240737963279;15305.846941530888;11370.715732106764;11540.538378465104;11562.30626162433;11595.188336816342;11793.115516336138;13235.602931912059;14263.500777472738;14316.928415357857;14473.92763036178;14638.414463421344 04:40:19 INFO - PID 1897 | |3;mootools.html;1482.399628150599;1531.655377540053;1545.6135966008499;1559.2203898050975;1568.2472413241646;9944.900550994487;13826.654333641663;14287.857121428782;14446.988710790249;14448.566543003713;13194;13956.51152209673;14080.225587592678;14322.641933951656;14458.843292536598;2783.846888421141;2813.535766598519;2879.6112524809196;2917.0665387076037;2946.8231906085703;2800.9439811203765;2845.601615773793;2852.957205641917;2855.4437831381947;2891.7975741698087;2745.7803375729895;2793.1480898326004;2810.142906413543;2872.755815755663;2899.4201159768027;2818.2109009477276;2829.5614179802164;2855.842928638924;2876.424715056976;2940.823670531783;2791.5254406750696;2793.1201671473423;2877.424515096989;2888.9711102888914;2893.421315736861;13745.862541374658;13813.171209727449;13896.374663140183;13952.511662091778;14111.871050315951;6372.203474565679;7436.628168591549;7501.062367204099;7893.644785984645;8041.678332866678;4895.167821470359;4897.7795999180125;4927;5022.070916880389;5069.366329208849;1399.979000314996;1442.668186317153;1464.816897887764;1472.9263536823223;1501.1218437214243;20733.68899466509;20908.895455522776;21145.788542114537;21370.46573835651;21393.78606213918;6352.904706429407;6572.76995305162;6727.293634168342;6767.966160169168;6797.6601169941305;14196.787048194285;14364.281785910664;14492.275386230645;14751.77872331916;14775.778363324558;6208.0998255253635;7508.211637778064;7588.203238659971;7710.884336734954;7711.691532338644;6528.347165283433;6910.896336554955;7095.716171353191;7132.358087772125;7155.785326440215;5003.074431230161;5187.610929180251;5212.218167274939;5283.894322113536;5285.392179899284;1284;1512.3648067811546;1516.4540765324475;1566.8354822743677;1570.7879436276185;760.4258784617689;791.7055614071036;838.4801423117706;861.5692153923038;868.9217970382696;6910.792676219721;7241.275872412716;7350.154732205758;7350.779476615603;7378.667959941816;625.3184029407967;675.4866301610713;681.3186813186813;703.5848849179009;722.7073035420663;4128.979355103265;4244.448221731217;5486.506214440719;5507.917381239285;5666.320041595035;18638.16128274231;18768.24927002932;18839.340623078126;19017.14422850975;19095.331663391713;18466;19094.809051909582;19137.2345106197;19272.614547708967;19320.550958678155;8832.91167088321;9060.003399626035;9817.460039697886;9850.11348978581;9873.851892221623;7761.512960817909;7926.652469080155;8171.591420428836;8346;8372.94862758628;16668.66662666764;16770.748438773673;17263.741043884602;17279.481615551555;17328.826711733225;16597.668046639243;16924.57688557796;17576.91211543934;17687.55781105431;17897.37359192422;10974.231803773659;11085.889141108486;11110.888911108825;11167.939045790637;11339.433028348714;12261.69345766363;12500.812422819818;12503.187292825936;12522.561710340095;12641.557545485864;9838.311318207656;9852.211823053995;10225.386476811414;10303.587856485656;10682.412467314372;9724.416535007922;9766.46284454362;9810.264230182622;10031.047050530187;10282.920293369089;9147.908520914705;9162.67930622425;9173.724788256362;9304.488253146143;9490.28822838276;6230.7507699691505;6588.912829383098;6709;7019.894701579583;7069.787906362817;6764.391204791593;6890.689918953559;7091.964540177266;7325.670344834389;7338.192798792022 04:40:19 INFO - PID 1897 | |4;prototype.html;415.61347946409836;448.4170578248276;459.1803630519523;482.0864461844804;484.2131536253586;5080.949190508094;6410.935890641093;6453;6527.281998980108;6546.577766226503;4760.9523904760945;6133.20268365113;6227.657478838662;6323.557350985433;6341.619502829833;3832.3485007548784;3892.8832135035996;3945.447637330783;4524.23088075028;4777.498362671921;4651.3720647712635;4701.48283688794;4825.90348193036;4878.707277563358;4893.748875517266;4346.478422589294;4663.860084197479;4718.41019872516;4742.834000809955;4775.450823155347;4550.384613462223;4653.976730116362;4708;4755.096531658974;4777.617790576745;3847.9230415391535;4089.20260549193;4205.495340559152;4211.241976444238;4257.701960862741;5173.8876141629735;6096.939030609681;6137.294211165684;6198.535109866778;6245.688405434864;5419.945800541984;5528.861778455571;5565.916511252294;5676.8864622707315;5721.166818231794;6675.165604299463;6697.330266973264;6877.415419689332;6889.586624802478;6927.7228910843505;4106.1582375612925;4731.574158325767;4766.785494652714;4786.377770889762;4870.780814863339;6044.760824031063;6688.331166883273;6950.895736563955;7254.419646428326;7303.89044164338;4041.7574945502774;4803.327534145223;4876.171050921352;4953.984433191187;4975.427825800007;4112.383142528644;4136.73111247768;4964.255361695775;5017.272495488134;5080.923786143211;4667.159911215946;5611.018071837412;5650.4349565043985;5739.139129130664;5768.653880767168;6687.963365678328;6754.763583274562;6868.244493105754;6895.965520172367;6913.619750913748;1596.2098761113323;1657.5110342449007;1695.177838748206;1706.5136436115647;1722.2519143069794;1121.624255874275;1133.9092872570175;1156.6240971684235;1161.4425075963416;1164.7495788405533;6204.224471941007;6215.844603884939;6345.619262844152;6414.198225221848;6515.315891831289;6908.309169083152;7021.859562808715;7066.646667666596;7080.672084120455;7107.92892071083;5684.403137670568;5792.739326730307;6490.643014634146;6541.649802881126;6556.63938483388;3116.9064928052194;3497.842597083137;3659.0669379308106;3671.541057367829;3713.3316003119157;7115.505743793861;7222;7236.963815180996;7394.482386232912;7409.110676777387;5730.140478928194;6255.218097737783;6678.966605166943;6936.271691472423;7078.858422831515;6127.632342059491;7169.737769314949;7169.749058783022;7315.4147668186415;7323.8535229296185;6883.414909732729;7033;7043.471739619651;7104.751333703295;7106.005159277809;6027.758889644365;6738.820706376463;6860.759873404407;6908.827279318058;6952.582845029315;5466.961277357289;5577.581681373995;6541.672916354259;6840.589564626139;7125.216226215216;4770.950390913993;4773.021530586291;4834.250691142878;4880.755962201947;4940.827071052496;5539.8892022160135;6325.557210995186;7016.228214896255;7041.788746337618;7069.222385537484;5161.870953226199;5610.297203845443;6803.795886123424;6819;6825.965870170618;5575.916361254665;6642.667866606747;6685.933140668531;6769.661516924036;6802.217744959195;5806;6471.223453185459;6474.676266186578;6488.383603557654;6657.667116644052;6661.367170118831;6684.63234522106;6719.764808231688;6789.253182150058;6818.829529261808;5425.376081750649;6464.353564643498;6575.638339891352;6642.136522252076;6644.169478815148 04:40:19 INFO - PID 1897 | |5;yui.html;805.2350267246117;850.2305413600693;850.978725531862;873.2402809555688;884.747846863644;14662.78005829912;17292.567685807862;17335.21991510382;17507.29970801168;17669.116544172804;13501.662458438544;14194.858051419482;14202.928985355085;14257;14861.628459288524;699.5977313044993;958.6261358070324;974.2157563161652;1008.2488546033215;1009.5255230041869;1004.1013293102669;1021.9744506387362;1022.151614160249;1029.4080903480528;1044.902852005395;1055.3140458701828;1060.194252368202;1075.9885707435026;1083.4257843343041;1089.5478376473793;946.6355453150518;1057.444841458233;1072.2869291920902;1074.656110044786;1085.2674444749803;785.6150486261748;790.5019837502394;796.5061661769682;814.4543156085437;817.4073796497552;17111.40110096153;17219.569510762205;17580.29678812846;17667.646647067115;17905;16404.67190656192;17408.825911740973;17637.294508219657;17738.733918991227;17814.198361073784;14590.124592524482;14609.92695036518;14792;14920.104793712304;15025.624359390993;3528.894133176009;3664.450332450154;3671.94492082619;3696.630336966282;3697.482352470656;98964.99999999929;121450;146531;147922.26038869846;148756;11281;11935.701607459709;12375.133740638248;14204.218767967654;14556.708865822837;26003.959841606502;26440.8677956609;26582.335441614116;27087;27098.322541936213;7992.160823113606;8046.637901294456;8155.918440815634;8241.175882411711;8300.750977470685;3924.3328634132267;4046.7976601169826;4047.919041619151;4082.081531655354;4113.814878330482;3637.363461394298;4066.6136717011836;4607.124646317256;4757.3101900224965;4758.690685105457;952.3904700991371;993.4436715439377;1008.3899240959114;1031.515187861719;1047.6542740895486;656.8587753632992;658.9934100659028;659.2517492645785;665.8368699668515;666.4335314982226;11013.339199647886;12047.518099276105;12359.387727565889;12875.871241287654;13050.54323098687;621.8103478439119;626.0118726389585;650.1742786660914;654.5058480847025;688.0573614148649;4404.8458303959205;4845.3767987724505;5008.873003574239;5064.772085256172;5855.912161317584;132252;133398.33300833628;137112.62887371;141303;142051.28974355062;114589;116869.83130168589;119234;125992.7400725981;127739;98762.5061874686;100050;100896.49551752194;100990;101905;11734.51557179813;12297.32364719949;12660.493580256687;12690.984721222465;12744.6813829655;44220.55779442293;45154.77422612866;45571.544284558055;46141.077178456915;48386.75806620944;69068;83413.91465213418;83512.16487835044;85792.57103714441;88424;14645.121292722471;17313.134343283036;17686.20412081485;17764.911175444042;18119.637607248045;18917.621647566695;19209.32767353136;19847.801521984595;19853.305134320228;19879.30422435208;15705.764413534034;16244;17812.91093544524;17926.462206133838;18120.456386308433;16009.279582419053;17155.571110722332;17744.46766597004;17806.46580602584;18146;17465.952042877467;17468.30126794914;17546.73679894777;17615.383461578782;17878.91060544689;10869.278330359917;11746.119041071783;11769.234999724991;12091.939540302594;12297.815532766837;13864.930675346559;14114.435422582981;14120;14362.13827170373;14686.339114740076 04:40:19 INFO - PID 1897 | __end_tp_report 04:40:19 INFO - PID 1897 | __start_cc_report 04:40:19 INFO - PID 1897 | _x_x_mozilla_cycle_collect,395 04:40:19 INFO - PID 1897 | __end_cc_report 04:40:19 INFO - PID 1897 | __startTimestamp1497440419630__endTimestamp 04:40:19 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | ------- Summary: start ------- 04:40:19 INFO - PID 1897 | Number of tests: 6 04:40:19 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | [#0] dojo.html Cycles:165 Average:6206.16 Median:6452.58 stddev:752.01 (11.7%) stddev-sans-first:728.22 04:40:19 INFO - PID 1897 | Values: 3702.9 3829.7 3831.2 3864.2 3881.1 6164.7 6220.2 6245.6 6315.8 6445.1 5649.8 6130.6 6192.9 6247.0 6360.4 5371.6 5486.1 5560.5 5665.3 5777.1 5445.5 5512.9 5579.2 5652.4 5697.1 5271.8 5330.3 5450.9 5544.0 5605.3 5468.1 5520.9 5548.4 5634.5 5657.1 5343.5 5460.8 5534.7 5536.6 5638.5 6091.7 6150.7 6163.3 6243.1 6339.9 5610.7 6564.3 6701.7 6736.8 6878.8 4666.6 6684.7 6807.8 6846.3 6855.1 6238.8 6277.5 6319.0 6488.4 6511.9 6943.1 7092.0 7128.6 7146.8 7152.8 6440.7 6455.9 6568.5 6596.5 6631.5 5620.3 6779.5 6892.9 6923.2 6985.3 6576.4 6697.7 6804.1 6844.0 6865.7 6675.5 6683.5 6745.9 6770.6 6920.8 4647.8 5340.3 5457.7 5467.6 5472.8 4609.7 4625.7 4669.5 4700.3 4756.7 6137.3 6142.9 6281.2 6361.9 6485.0 5528.7 6839.1 6963.6 6996.7 7000.3 6363.9 6366.5 6381.2 6510.7 6587.7 5862.0 5887.3 5951.4 6009.1 6052.7 6665.6 6820.5 7013.5 7070.7 7099.9 6658.5 6669.3 6695.6 6835.5 6837.6 6820.3 7011.1 7017.6 7134.4 7145.2 6653.5 6655.9 6732.7 6762.5 6863.5 5470.5 6770.6 6796.9 6844.6 6971.2 6531.2 6599.5 6657.9 6744.2 6751.8 5425.9 6453.0 6453.0 6502.3 6673.7 6313.2 6481.0 6616.8 6676.2 6692.4 6616.4 6638.5 6766.0 6856.7 6860.7 5256.7 6279.9 6304.9 6444.3 6452.6 04:40:19 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | [#1] ext.html Cycles:180 Average:45333.95 Median:327178.00 stddev:77842.40 (23.8%) stddev-sans-first:77988.65 04:40:19 INFO - PID 1897 | Values: 706.6 787.2 817.2 880.8 891.4 41364.8 42449.4 43496.6 43652.8 43889.6 42879.0 43375.8 43416.6 43705.6 43786.0 7561.1 7801.6 7914.7 7946.2 8233.1 8423.4 8484.4 8541.6 8660.0 8671.7 7523.0 8343.8 8598.4 8661.8 8699.9 8418.3 8419.7 8629.5 8657.9 8682.8 8262.6 8308.4 8467.2 8488.2 8516.0 31808.5 38458.4 39616.6 39848.4 39971.8 3607.2 3683.3 3686.3 3689.5 3739.6 30886.0 31921.7 31995.4 32284.7 32316.8 3058.0 3144.0 3189.4 3219.3 3245.6 260535.0 268789.0 273618.0 320061.4 326866.0 43081.6 50148.7 50945.2 50961.0 51747.0 41213.6 53547.7 54108.2 54117.7 54318.2 15699.6 15843.2 15939.4 16099.3 16101.3 12894.6 12994.9 13088.7 13131.2 13338.4 13898.6 14022.6 14129.2 14168.1 14330.6 5144.8 5830.0 5895.6 5980.0 6036.4 1007.2 1021.3 1022.9 1040.1 1083.9 20375.6 22804.5 23920.8 24182.1 25666.4 1127.9 1139.8 1165.5 1173.2 1197.7 9440.8 11775.3 12521.2 12671.4 12907.0 315457.0 315466.0 316533.0 323060.0 327490.0 57998.7 58020.0 58106.4 58203.0 58241.4 1527.6 1533.8 1556.0 1575.3 1592.9 23149.2 23183.8 23725.0 23861.6 24704.9 40187.0 40731.0 41358.6 41389.0 41585.6 271869.0 276330.0 280124.0 281440.0 281728.0 27775.3 30251.0 35047.8 35360.1 36176.8 43799.6 45129.1 46114.5 47025.0 47085.6 27598.3 29944.0 30982.0 31855.8 32011.7 25084.1 30710.5 30782.5 31374.4 32197.0 36780.8 37820.0 38201.8 38293.0 38691.4 22024.6 26163.7 27507.3 31899.8 32364.4 18962.0 19137.4 21074.0 24207.7 24694.6 04:40:19 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | [#2] jquery.html Cycles:180 Average:38064.62 Median:2234.00 stddev:91768.23 (4107.8%) stddev-sans-first:91982.22 04:40:19 INFO - PID 1897 | Values: 455.0 527.6 529.7 531.7 534.3 22458.7 23075.8 23742.9 23799.0 24996.5 22535.0 22946.8 23617.8 24796.8 25338.9 6366.8 6403.3 6572.4 6614.9 6640.9 5263.0 6097.8 6257.1 6292.3 6453.4 6268.7 6366.9 6423.2 6451.7 6490.7 6303.0 6463.5 6471.5 6473.4 6491.5 5734.3 5840.1 5910.3 5950.7 5952.0 28962.0 29388.6 29732.6 29739.0 30745.8 2223.8 2311.5 2314.5 2346.8 2388.7 19352.7 19624.8 19668.5 19850.5 19874.4 2147.0 2196.4 2224.9 2233.3 2234.7 163503.0 167654.0 173636.1 175463.0 200129.0 13742.2 14948.9 15332.3 15694.2 15720.6 20858.4 21013.5 21084.8 21494.9 21509.2 10044.4 10205.4 10209.9 10490.7 10592.9 7558.4 9609.1 9747.0 9932.0 10013.7 9182.8 9214.9 9363.9 9378.6 9412.9 2206.8 2329.7 2607.6 2608.3 2667.2 922.7 923.6 941.4 999.0 999.3 17183.4 20146.6 20828.4 21537.1 22236.1 978.2 983.2 1012.1 1016.2 1056.8 8881.2 10680.3 10771.5 10935.3 11253.3 510899.0 525794.0 527371.0 530518.0 551171.0 141346.3 144708.3 154221.0 159703.0 172619.0 1055.7 1087.4 1109.7 1214.7 1286.8 21439.7 21984.6 23314.9 23386.9 23562.6 47274.5 67854.7 69066.6 69432.0 70107.0 75355.7 90836.0 91054.1 93974.1 94342.0 11765.8 11811.9 11850.0 11879.9 11917.2 17808.0 17956.7 18267.1 18294.6 18624.9 14389.4 14744.5 15093.0 15266.0 15311.5 12655.5 13068.7 13133.0 13275.4 13675.2 14765.9 15096.0 15164.0 15185.2 15305.8 11370.7 11540.5 11562.3 11595.2 11793.1 13235.6 14263.5 14316.9 14473.9 14638.4 04:40:19 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | [#3] mootools.html Cycles:180 Average:8463.88 Median:2878.52 stddev:5696.02 (197.9%) stddev-sans-first:5687.85 04:40:19 INFO - PID 1897 | Values: 1482.4 1531.7 1545.6 1559.2 1568.2 9944.9 13826.7 14287.9 14447.0 14448.6 13194.0 13956.5 14080.2 14322.6 14458.8 2783.8 2813.5 2879.6 2917.1 2946.8 2800.9 2845.6 2853.0 2855.4 2891.8 2745.8 2793.1 2810.1 2872.8 2899.4 2818.2 2829.6 2855.8 2876.4 2940.8 2791.5 2793.1 2877.4 2889.0 2893.4 13745.9 13813.2 13896.4 13952.5 14111.9 6372.2 7436.6 7501.1 7893.6 8041.7 4895.2 4897.8 4927.0 5022.1 5069.4 1400.0 1442.7 1464.8 1472.9 1501.1 20733.7 20908.9 21145.8 21370.5 21393.8 6352.9 6572.8 6727.3 6768.0 6797.7 14196.8 14364.3 14492.3 14751.8 14775.8 6208.1 7508.2 7588.2 7710.9 7711.7 6528.3 6910.9 7095.7 7132.4 7155.8 5003.1 5187.6 5212.2 5283.9 5285.4 1284.0 1512.4 1516.5 1566.8 1570.8 760.4 791.7 838.5 861.6 868.9 6910.8 7241.3 7350.2 7350.8 7378.7 625.3 675.5 681.3 703.6 722.7 4129.0 4244.4 5486.5 5507.9 5666.3 18638.2 18768.2 18839.3 19017.1 19095.3 18466.0 19094.8 19137.2 19272.6 19320.6 8832.9 9060.0 9817.5 9850.1 9873.9 7761.5 7926.7 8171.6 8346.0 8372.9 16668.7 16770.7 17263.7 17279.5 17328.8 16597.7 16924.6 17576.9 17687.6 17897.4 10974.2 11085.9 11110.9 11167.9 11339.4 12261.7 12500.8 12503.2 12522.6 12641.6 9838.3 9852.2 10225.4 10303.6 10682.4 9724.4 9766.5 9810.3 10031.0 10282.9 9147.9 9162.7 9173.7 9304.5 9490.3 6230.8 6588.9 6709.0 7019.9 7069.8 6764.4 6890.7 7092.0 7325.7 7338.2 04:40:19 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | [#4] prototype.html Cycles:180 Average:5474.93 Median:6070.85 stddev:1686.68 (27.8%) stddev-sans-first:1648.11 04:40:19 INFO - PID 1897 | Values: 415.6 448.4 459.2 482.1 484.2 5080.9 6410.9 6453.0 6527.3 6546.6 4761.0 6133.2 6227.7 6323.6 6341.6 3832.3 3892.9 3945.4 4524.2 4777.5 4651.4 4701.5 4825.9 4878.7 4893.7 4346.5 4663.9 4718.4 4742.8 4775.5 4550.4 4654.0 4708.0 4755.1 4777.6 3847.9 4089.2 4205.5 4211.2 4257.7 5173.9 6096.9 6137.3 6198.5 6245.7 5419.9 5528.9 5565.9 5676.9 5721.2 6675.2 6697.3 6877.4 6889.6 6927.7 4106.2 4731.6 4766.8 4786.4 4870.8 6044.8 6688.3 6950.9 7254.4 7303.9 4041.8 4803.3 4876.2 4954.0 4975.4 4112.4 4136.7 4964.3 5017.3 5080.9 4667.2 5611.0 5650.4 5739.1 5768.7 6688.0 6754.8 6868.2 6896.0 6913.6 1596.2 1657.5 1695.2 1706.5 1722.3 1121.6 1133.9 1156.6 1161.4 1164.7 6204.2 6215.8 6345.6 6414.2 6515.3 6908.3 7021.9 7066.6 7080.7 7107.9 5684.4 5792.7 6490.6 6541.6 6556.6 3116.9 3497.8 3659.1 3671.5 3713.3 7115.5 7222.0 7237.0 7394.5 7409.1 5730.1 6255.2 6679.0 6936.3 7078.9 6127.6 7169.7 7169.7 7315.4 7323.9 6883.4 7033.0 7043.5 7104.8 7106.0 6027.8 6738.8 6860.8 6908.8 6952.6 5467.0 5577.6 6541.7 6840.6 7125.2 4771.0 4773.0 4834.3 4880.8 4940.8 5539.9 6325.6 7016.2 7041.8 7069.2 5161.9 5610.3 6803.8 6819.0 6826.0 5575.9 6642.7 6685.9 6769.7 6802.2 5806.0 6471.2 6474.7 6488.4 6657.7 6661.4 6684.6 6719.8 6789.3 6818.8 5425.4 6464.4 6575.6 6642.1 6644.2 04:40:19 INFO - PID 1897 | 04:40:19 INFO - PID 1897 | [#5] yui.html Cycles:180 Average:25342.52 Median:17626.34 stddev:38087.11 (216.1%) stddev-sans-first:38149.39 04:40:19 INFO - PID 1897 | Values: 805.2 850.2 851.0 873.2 884.7 14662.8 17292.6 17335.2 17507.3 17669.1 13501.7 14194.9 14202.9 14257.0 14861.6 699.6 958.6 974.2 1008.2 1009.5 1004.1 1022.0 1022.2 1029.4 1044.9 1055.3 1060.2 1076.0 1083.4 1089.5 946.6 1057.4 1072.3 1074.7 1085.3 785.6 790.5 796.5 814.5 817.4 17111.4 17219.6 17580.3 17667.6 17905.0 16404.7 17408.8 17637.3 17738.7 17814.2 14590.1 14609.9 14792.0 14920.1 15025.6 3528.9 3664.5 3671.9 3696.6 3697.5 98965.0 121450.0 146531.0 147922.3 148756.0 11281.0 11935.7 12375.1 14204.2 14556.7 26004.0 26440.9 26582.3 27087.0 27098.3 7992.2 8046.6 8155.9 8241.2 8300.8 3924.3 4046.8 4047.9 4082.1 4113.8 3637.4 4066.6 4607.1 4757.3 4758.7 952.4 993.4 1008.4 1031.5 1047.7 656.9 659.0 659.3 665.8 666.4 11013.3 12047.5 12359.4 12875.9 13050.5 621.8 626.0 650.2 654.5 688.1 4404.8 4845.4 5008.9 5064.8 5855.9 132252.0 133398.3 137112.6 141303.0 142051.3 114589.0 116869.8 119234.0 125992.7 127739.0 98762.5 100050.0 100896.5 100990.0 101905.0 11734.5 12297.3 12660.5 12691.0 12744.7 44220.6 45154.8 45571.5 46141.1 48386.8 69068.0 83413.9 83512.2 85792.6 88424.0 14645.1 17313.1 17686.2 17764.9 18119.6 18917.6 19209.3 19847.8 19853.3 19879.3 15705.8 16244.0 17812.9 17926.5 18120.5 16009.3 17155.6 17744.5 17806.5 18146.0 17466.0 17468.3 17546.7 17615.4 17878.9 10869.3 11746.1 11769.2 12091.9 12297.8 13864.9 14114.4 14120.0 14362.1 14686.3 04:40:19 INFO - PID 1897 | -------- Summary: end -------- 04:40:19 INFO - PID 1897 | 04:40:20 INFO - TEST-INFO | 1897: exit 0 04:40:20 INFO - TEST-OK | dromaeo_css | took 1142599ms 04:40:20 INFO - TEST-START | kraken 04:40:20 INFO - operating with platform_type : mac_ 04:40:20 INFO - Initialising browser for kraken test... 04:40:20 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49300/getInfo.html -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpiJiBEq/profile 04:40:20 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49300/getInfo.html -foreground) 04:40:52 INFO - TEST-INFO | 1906: killed by SIGTERM 04:40:52 INFO - Browser initialized. 04:40:52 INFO - Running cycle 1/1 for kraken test... 04:40:52 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpiJiBEq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:54 INFO - PID 1909 | 2017-06-14 04:40:54.945 plugin-container[1910:11268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954b, name = 'com.apple.tsm.portname' 04:40:54 INFO - PID 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:54 INFO - PID 1909 | 2017-06-14 04:40:54.945 plugin-container[1910:11268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 04:40:54 INFO - PID 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:54 INFO - PID 1909 | 2017-06-14 04:40:54.945 plugin-container[1910:11268] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:41:04 INFO - PID 1909 | RSS: Main: 184606720 04:41:04 INFO - PID 1909 | 04:41:07 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49300/tests/kraken/driver.html?testName=audio-beat-detection) 04:41:08 INFO - PID 1909 | RSS: Main: 198139904 04:41:08 INFO - PID 1909 | 04:41:10 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49300/tests/kraken/driver.html?testName=audio-dft) 04:41:11 INFO - PID 1909 | RSS: Main: 207269888 04:41:11 INFO - PID 1909 | 04:41:14 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49300/tests/kraken/driver.html?testName=audio-fft) 04:41:15 INFO - PID 1909 | RSS: Main: 211255296 04:41:15 INFO - PID 1909 | 04:41:17 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49300/tests/kraken/driver.html?testName=audio-oscillator) 04:41:18 INFO - PID 1909 | RSS: Main: 213028864 04:41:18 INFO - PID 1909 | 04:41:20 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49300/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:41:20 INFO - PID 1909 | RSS: Main: 214872064 04:41:20 INFO - PID 1909 | 04:41:23 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49300/tests/kraken/driver.html?testName=imaging-darkroom) 04:41:24 INFO - PID 1909 | RSS: Main: 219193344 04:41:24 INFO - PID 1909 | 04:41:27 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49300/tests/kraken/driver.html?testName=imaging-desaturate) 04:41:28 INFO - PID 1909 | RSS: Main: 219496448 04:41:28 INFO - PID 1909 | 04:41:31 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49300/tests/kraken/driver.html?testName=json-parse-financial) 04:41:31 INFO - PID 1909 | RSS: Main: 219578368 04:41:31 INFO - PID 1909 | 04:41:33 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49300/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:41:33 INFO - PID 1909 | RSS: Main: 218951680 04:41:33 INFO - PID 1909 | 04:41:35 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49300/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:41:36 INFO - PID 1909 | RSS: Main: 218812416 04:41:36 INFO - PID 1909 | 04:41:38 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49300/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:41:39 INFO - PID 1909 | RSS: Main: 219295744 04:41:39 INFO - PID 1909 | 04:41:41 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49300/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:41:42 INFO - PID 1909 | RSS: Main: 219381760 04:41:42 INFO - PID 1909 | 04:41:45 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49300/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:41:46 INFO - PID 1909 | RSS: Main: 219148288 04:41:46 INFO - PID 1909 | 04:41:47 INFO - PID 1909 | Cycle 1(1): loaded http://localhost:49300/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49300/tests/kraken/driver.html?testName=ai-astar) 04:41:48 INFO - PID 1909 | RSS: Main: 221188096 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | __start_tp_report 04:41:48 INFO - PID 1909 | _x_x_mozilla_page_load 04:41:48 INFO - PID 1909 | _x_x_mozilla_page_load_details 04:41:48 INFO - PID 1909 | |i|pagename|runs| 04:41:48 INFO - PID 1909 | |0;ai-astar;102;114;106;115;114;116;115;103;115;113 04:41:48 INFO - PID 1909 | |1;audio-beat-detection;141;110;147;152;129;120;146;101;180;146 04:41:48 INFO - PID 1909 | |2;audio-dft;203;209;200;199;197;197;198;205;205;203 04:41:48 INFO - PID 1909 | |3;audio-fft;65;84;75;155;83;88;85;80;138;80 04:41:48 INFO - PID 1909 | |4;audio-oscillator;69;73;114;70;70;73;104;68;73;119 04:41:48 INFO - PID 1909 | |5;imaging-gaussian-blur;95;93;89;83;121;138;109;94;90;91 04:41:48 INFO - PID 1909 | |6;imaging-darkroom;97;106;98;96;151;126;92;152;103;154 04:41:48 INFO - PID 1909 | |7;imaging-desaturate;100;91;89;94;92;91;89;93;93;92 04:41:48 INFO - PID 1909 | |8;json-parse-financial;50;49;51;51;48;51;51;58;81;43 04:41:48 INFO - PID 1909 | |9;json-stringify-tinderbox;55;53;56;55;85;89;49;52;74;84 04:41:48 INFO - PID 1909 | |10;stanford-crypto-aes;83;78;69;97;72;90;92;99;106;101 04:41:48 INFO - PID 1909 | |11;stanford-crypto-ccm;136;135;130;137;151;150;160;139;143;166 04:41:48 INFO - PID 1909 | |12;stanford-crypto-pbkdf2;180;178;180;178;179;175;182;187;175;179 04:41:48 INFO - PID 1909 | |13;stanford-crypto-sha256-iterative;67;63;63;65;65;63;54;65;70;94 04:41:48 INFO - PID 1909 | __end_tp_report 04:41:48 INFO - PID 1909 | __start_cc_report 04:41:48 INFO - PID 1909 | _x_x_mozilla_cycle_collect,988 04:41:48 INFO - PID 1909 | __end_cc_report 04:41:48 INFO - PID 1909 | __startTimestamp1497440508158__endTimestamp 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | ------- Summary: start ------- 04:41:48 INFO - PID 1909 | Number of tests: 14 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#0] ai-astar Cycles:10 Average:111.30 Median:114.50 stddev:5.42 (4.7%) stddev-sans-first:4.58 04:41:48 INFO - PID 1909 | Values: 102.0 114.0 106.0 115.0 114.0 116.0 115.0 103.0 115.0 113.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#1] audio-beat-detection Cycles:10 Average:137.20 Median:146.00 stddev:22.92 (15.7%) stddev-sans-first:24.27 04:41:48 INFO - PID 1909 | Values: 141.0 110.0 147.0 152.0 129.0 120.0 146.0 101.0 180.0 146.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#2] audio-dft Cycles:10 Average:201.60 Median:203.00 stddev:4.03 (2.0%) stddev-sans-first:4.25 04:41:48 INFO - PID 1909 | Values: 203.0 209.0 200.0 199.0 197.0 197.0 198.0 205.0 205.0 203.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#3] audio-fft Cycles:10 Average:93.30 Median:81.50 stddev:29.03 (35.6%) stddev-sans-first:28.93 04:41:48 INFO - PID 1909 | Values: 65.0 84.0 75.0 155.0 83.0 88.0 85.0 80.0 138.0 80.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:70.00 stddev:20.43 (29.2%) stddev-sans-first:21.00 04:41:48 INFO - PID 1909 | Values: 69.0 73.0 114.0 70.0 70.0 73.0 104.0 68.0 73.0 119.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#5] imaging-gaussian-blur Cycles:10 Average:100.30 Median:90.50 stddev:17.21 (19.0%) stddev-sans-first:18.15 04:41:48 INFO - PID 1909 | Values: 95.0 93.0 89.0 83.0 121.0 138.0 109.0 94.0 90.0 91.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#6] imaging-darkroom Cycles:10 Average:117.50 Median:123.00 stddev:25.76 (20.9%) stddev-sans-first:26.23 04:41:48 INFO - PID 1909 | Values: 97.0 106.0 98.0 96.0 151.0 126.0 92.0 152.0 103.0 154.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:92.00 stddev:3.13 (3.4%) stddev-sans-first:1.74 04:41:48 INFO - PID 1909 | Values: 100.0 91.0 89.0 94.0 92.0 91.0 89.0 93.0 93.0 92.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:51.00 stddev:10.40 (20.4%) stddev-sans-first:10.97 04:41:48 INFO - PID 1909 | Values: 50.0 49.0 51.0 51.0 48.0 51.0 51.0 58.0 81.0 43.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:15.87 (24.4%) stddev-sans-first:16.40 04:41:48 INFO - PID 1909 | Values: 55.0 53.0 56.0 55.0 85.0 89.0 49.0 52.0 74.0 84.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:86.50 stddev:12.70 (14.7%) stddev-sans-first:13.30 04:41:48 INFO - PID 1909 | Values: 83.0 78.0 69.0 97.0 72.0 90.0 92.0 99.0 106.0 101.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:146.50 stddev:11.72 (8.0%) stddev-sans-first:12.00 04:41:48 INFO - PID 1909 | Values: 136.0 135.0 130.0 137.0 151.0 150.0 160.0 139.0 143.0 166.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.30 Median:179.50 stddev:3.47 (1.9%) stddev-sans-first:3.67 04:41:48 INFO - PID 1909 | Values: 180.0 178.0 180.0 178.0 179.0 175.0 182.0 187.0 175.0 179.0 04:41:48 INFO - PID 1909 | 04:41:48 INFO - PID 1909 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:65.00 stddev:10.37 (15.9%) stddev-sans-first:10.99 04:41:48 INFO - PID 1909 | Values: 67.0 63.0 63.0 65.0 65.0 63.0 54.0 65.0 70.0 94.0 04:41:48 INFO - PID 1909 | -------- Summary: end -------- 04:41:48 INFO - PID 1909 | 04:41:48 INFO - TEST-INFO | 1909: exit 0 04:41:48 INFO - TEST-OK | kraken | took 88762ms 04:41:48 INFO - SUITE-END | took 1231s 04:41:49 INFO - Completed test suite (00:20:31) 04:41:49 INFO - javascript benchmark 04:41:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8388.842536529168, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3702.9444558331647, 3829.7127715421343, 3831.2146010067922, 3864.1885204107143, 3881.0879443330814, 6164.660943648098, 6220.222472190976, 6245.625262484243, 6315.8105256842255, 6445.129907462491, 5649.830505084854, 6130.551011451152, 6192.938070619292, 6246.968765156179, 6360.427561519462, 5371.57027437805, 5486.1222204447295, 5560.527355174815, 5665.348484924225, 5777.104548794922, 5445.53712934399, 5512.91730624041, 5579.246801681782, 5652.434756524356, 5697.1169468732205, 5271.789128434858, 5330.333708286465, 5450.91823622646, 5543.972280138614, 5605.2713147291, 5468.070428027245, 5520.944790552124, 5548.44515548446, 5634.492895639412, 5657.094864821609, 5343.465653434664, 5460.754266058037, 5534.695591742453, 5536.612437129403, 5638.548916086745, 6091.695415229221, 6150.7232174552255, 6163.260408750979, 6243.125962365272, 6339.904901426482, 5610.663360198401, 6564.310747371505, 6701.698423570951, 6736.797896063126, 6878.793636190922, 4666.556677115668, 6684.699188536528, 6807.761728339485, 6846.315368463163, 6855.143107111611, 6238.754048542521, 6277.466415354746, 6318.968405157991, 6488.351164883521, 6511.902321465182, 6943.062686537354, 7091.999999999897, 7128.607926563985, 7146.821329466805, 7152.785416437515, 6440.677966101676, 6455.870882582322, 6568.507361947873, 6596.472282217411, 6631.469482441393, 5620.269364982526, 6779.49153813466, 6892.931070689329, 6923.169219693669, 6985.301469853076, 6576.408123268929, 6697.665116744143, 6804.149481314836, 6844.041834143224, 6865.725370985205, 6675.465962723068, 6683.465322774166, 6745.898811517831, 6770.627615481098, 6920.792376228721, 4647.790849411785, 5340.305760251143, 5457.672539647634, 5467.617266791286, 5472.835814925558, 4609.654275929319, 4625.722456652612, 4669.4630117536235, 4700.341952126705, 4756.6908150970075, 6137.263528376534, 6142.942703134429, 6281.246250449975, 6361.9045714314325, 6485.0, 5528.668279903219, 6839.1451068616425, 6963.582185068912, 6996.6851491683, 7000.26497217795, 6363.872722545523, 6366.490680745622, 6381.202349706286, 6510.674466276762, 6587.736490540325, 5861.970690146607, 5887.293524777054, 5951.434613711691, 6009.098635204754, 6052.66710330936, 6665.633390163587, 6820.488463365366, 7013.509054366247, 7070.681819318246, 7099.929000709927, 6658.467322614276, 6669.266380698216, 6695.564788288941, 6835.521513494206, 6837.555558888656, 6820.283870193558, 7011.088558487364, 7017.578945263301, 7134.429245660231, 7145.1783044948415, 6653.534252602271, 6655.900161497679, 6732.730690772314, 6762.49281303914, 6863.450923925974, 5470.53500452466, 6770.627615481196, 6796.932030679828, 6844.623545705034, 6971.2331643518155, 6531.248906375828, 6599.53803233769, 6657.933420665731, 6744.190697116377, 6751.831204219738, 5425.918611220756, 6453.0, 6453.022867701275, 6502.284748677547, 6673.699683514351, 6313.179286692701, 6480.967595161994, 6616.768413105518, 6676.198856137295, 6692.364225398579, 6616.371444712745, 6638.53530252897, 6765.985102234507, 6856.691448884712, 6860.725570977105, 5256.710880901587, 6279.905801412891, 6304.873902522016, 6444.258910225197, 6452.580582262326], "value": 6161.310034727428, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.6007705646309, 787.1577412168978, 817.219555324665, 880.788610733424, 891.4116682989225, 41364.79317603415, 42449.36325955113, 43496.565034349645, 43652.781736091354, 43889.56110438899, 42879.0, 43375.78312108435, 43416.56583434172, 43705.56294437055, 43786.0, 7561.054868141482, 7801.609919504031, 7914.722984695537, 7946.205379462036, 8233.135520770293, 8423.36824738144, 8484.36367272454, 8541.615627296755, 8659.95670021649, 8671.739847804576, 7523.0, 8343.83312333756, 8598.441101328424, 8661.826763464727, 8699.869501957475, 8418.284445822112, 8419.70531031414, 8629.525376104311, 8657.870131948026, 8682.826343473094, 8262.586870656443, 8308.418410711252, 8467.195616416431, 8488.236058754741, 8516.0, 31808.522872156937, 38458.42312365317, 39616.60383396158, 39848.40227396562, 39971.80014099911, 3607.2244467439627, 3683.3001729671278, 3686.2627474505207, 3689.5203623529037, 3739.607341229159, 30886.0, 31921.680783192103, 31995.360092798015, 32284.708611655504, 32316.838415808004, 3058.0, 3143.984280078585, 3189.409959157563, 3219.2917558137174, 3245.594300712411, 260535.0, 268789.0, 273617.999999998, 320061.3996930001, 326866.0, 43081.56918430776, 50148.74925625422, 50945.23582146271, 50961.0, 51747.0, 41213.58786412098, 53547.732261338446, 54108.188377174374, 54117.72941135269, 54318.18522722162, 15699.607509812118, 15843.207839607974, 15939.44211952576, 16099.275532601061, 16101.275442604876, 12894.613161605166, 12994.870051299555, 13088.738225235442, 13131.212127272394, 13338.399772010283, 13898.58304250874, 14022.649433764238, 14129.222892740792, 14168.149911005372, 14330.57008289753, 5144.765256338452, 5829.970850145807, 5895.64626122434, 5979.983402821531, 6036.366181550961, 1007.2244371833647, 1021.2851004297022, 1022.892596277395, 1040.105509262034, 1083.913286937043, 20375.592488150152, 22804.517706349034, 23920.7607923922, 24182.075396508902, 25666.358341041625, 1127.9492422840992, 1139.8347239650204, 1165.539611853313, 1173.1904985559936, 1197.6886009637383, 9440.811183776286, 11775.293482391084, 12521.248725076342, 12671.36643167838, 12907.0, 315457.0, 315466.0, 316533.0, 323060.0, 327490.0, 57998.71000645054, 58020.0, 58106.41893581094, 58203.0, 58241.41758582445, 1527.5722797616686, 1533.8389469105807, 1555.9922200388926, 1575.330484544087, 1592.8805339599576, 23149.189778357675, 23183.76816231816, 23725.0, 23861.642075368538, 24704.8882800278, 40187.0, 40730.9817254571, 41358.586414135476, 41389.0, 41585.58414415817, 271869.0, 276330.0, 280124.0, 281440.0, 281728.0, 27775.30561735973, 30251.0, 35047.824760876036, 35360.11599710028, 36176.8191159053, 43799.56200438082, 45129.0974180508, 46114.538854611026, 47025.0, 47085.587432377084, 27598.310042249104, 29943.951961681236, 30982.0, 31855.84072079625, 32011.679883200868, 25084.122055727963, 30710.539341910335, 30782.538261926537, 31374.372512549166, 32197.0, 36780.81609592042, 37820.0, 38201.808990954865, 38293.0, 38691.41962870616, 22024.559508810056, 26163.73836261613, 27507.31231719223, 31899.84050079735, 32364.35271294608, 18962.0, 19137.425877223704, 21074.0, 24207.668578228368, 24694.629580555946], "value": 17737.52595065828, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.9909001819964, 527.5647590737641, 529.7086602368696, 531.6836482293035, 534.3320848938827, 22458.663120053192, 23075.76924230757, 23742.931568079457, 23799.0, 24996.50006999859, 22534.985925633344, 22946.77053229467, 23617.763822361772, 24796.76016199192, 25338.873305633493, 6366.840828979278, 6403.263624683151, 6572.441342485894, 6614.8677026459445, 6640.867182656344, 5263.0, 6097.756089756404, 6257.092721555372, 6292.276388215345, 6453.419192272694, 6268.749250030016, 6366.872662546746, 6423.229212494484, 6451.741930322805, 6490.707918143671, 6302.968485157568, 6463.515236357292, 6471.546991710606, 6473.352664733536, 6491.513136514756, 5734.340550836666, 5840.094785308284, 5910.290765108214, 5950.672713000783, 5951.970240148771, 28961.986330478543, 29388.559171612444, 29732.554011689626, 29739.0, 30745.846270768503, 2223.7776222377793, 2311.5492478966607, 2314.4561028158537, 2346.7887890089805, 2388.7133543974833, 19352.70970935437, 19624.822510649406, 19668.50828729279, 19850.503737406536, 19874.403767886986, 2147.001644235422, 2196.352076137527, 2224.877631730254, 2233.3076746208567, 2234.698315727372, 163503.0, 167654.0, 173636.13181934136, 175462.99999999872, 200129.0, 13742.244176570384, 14948.925255373872, 15332.257210109046, 15694.199123831248, 15720.60698482547, 20858.37424877256, 21013.47466313324, 21084.789152108282, 21494.89252553727, 21509.247176348752, 10044.447555384524, 10205.438700871375, 10209.876913539505, 10490.737731556772, 10592.947035264775, 7558.433117516099, 9609.135177834029, 9746.976567460457, 9932.0, 10013.749656258651, 9182.77043073915, 9214.90785092154, 9363.90636093644, 9378.5779639915, 9412.858807117898, 2206.7682893295973, 2329.6738456616085, 2607.556715358394, 2608.2696844883462, 2667.1731763153484, 922.6586163119555, 923.5982347678781, 941.3504681769557, 999.0359303272377, 999.2855108597388, 17183.3985810496, 20146.597068058556, 20828.375148745257, 21537.077303741597, 22236.110555577594, 978.2076518020426, 983.1593987141034, 1012.1143999000874, 1016.2327442780653, 1056.804491169136, 8881.156290152425, 10680.252382333161, 10771.515281812337, 10935.343879367263, 11253.324800511995, 510899.0, 525794.0, 527371.0, 530518.0, 551171.0, 141346.29326853505, 144708.27645861913, 154221.0, 159703.0, 172619.0, 1055.714956961616, 1087.4127970895786, 1109.7003808971533, 1214.7084699672016, 1286.7876800328215, 21439.67840482363, 21984.560308793414, 23314.883425582764, 23386.883065584563, 23562.646560301266, 47274.527254727014, 67854.66072669605, 69066.61866762736, 69432.0, 70107.0, 75355.73932782025, 90836.0, 91054.08945910457, 93974.06025939653, 94342.0, 11765.823512647146, 11811.940940295588, 11849.992750616295, 11879.930806227481, 11917.22538035025, 17808.0, 17956.730649040015, 18267.086645667398, 18294.634107318045, 18624.906875465535, 14389.352479138674, 14744.483943061941, 15093.0, 15266.0, 15311.540653780405, 12655.493780249055, 13068.673283167996, 13133.015023873439, 13275.402606882906, 13675.24786136772, 14765.852341476448, 15096.0, 15164.0, 15185.240737963279, 15305.846941530888, 11370.715732106764, 11540.538378465104, 11562.30626162433, 11595.188336816342, 11793.115516336138, 13235.602931912059, 14263.500777472738, 14316.928415357857, 14473.92763036178, 14638.414463421344], "value": 11697.710975999898, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1482.399628150599, 1531.655377540053, 1545.6135966008499, 1559.2203898050975, 1568.2472413241646, 9944.900550994487, 13826.654333641663, 14287.857121428782, 14446.988710790249, 14448.566543003713, 13194.0, 13956.51152209673, 14080.225587592678, 14322.641933951656, 14458.843292536598, 2783.846888421141, 2813.535766598519, 2879.6112524809196, 2917.0665387076037, 2946.8231906085703, 2800.9439811203765, 2845.601615773793, 2852.957205641917, 2855.4437831381947, 2891.7975741698087, 2745.7803375729895, 2793.1480898326004, 2810.142906413543, 2872.755815755663, 2899.4201159768027, 2818.2109009477276, 2829.5614179802164, 2855.842928638924, 2876.424715056976, 2940.823670531783, 2791.5254406750696, 2793.1201671473423, 2877.424515096989, 2888.9711102888914, 2893.421315736861, 13745.862541374658, 13813.171209727449, 13896.374663140183, 13952.511662091778, 14111.871050315951, 6372.203474565679, 7436.628168591549, 7501.062367204099, 7893.644785984645, 8041.678332866678, 4895.167821470359, 4897.7795999180125, 4927.0, 5022.070916880389, 5069.366329208849, 1399.979000314996, 1442.668186317153, 1464.816897887764, 1472.9263536823223, 1501.1218437214243, 20733.68899466509, 20908.895455522776, 21145.788542114537, 21370.46573835651, 21393.78606213918, 6352.904706429407, 6572.76995305162, 6727.293634168342, 6767.966160169168, 6797.6601169941305, 14196.787048194285, 14364.281785910664, 14492.275386230645, 14751.77872331916, 14775.778363324558, 6208.0998255253635, 7508.211637778064, 7588.203238659971, 7710.884336734954, 7711.691532338644, 6528.347165283433, 6910.896336554955, 7095.716171353191, 7132.358087772125, 7155.785326440215, 5003.074431230161, 5187.610929180251, 5212.218167274939, 5283.894322113536, 5285.392179899284, 1284.0, 1512.3648067811546, 1516.4540765324475, 1566.8354822743677, 1570.7879436276185, 760.4258784617689, 791.7055614071036, 838.4801423117706, 861.5692153923038, 868.9217970382696, 6910.792676219721, 7241.275872412716, 7350.154732205758, 7350.779476615603, 7378.667959941816, 625.3184029407967, 675.4866301610713, 681.3186813186813, 703.5848849179009, 722.7073035420663, 4128.979355103265, 4244.448221731217, 5486.506214440719, 5507.917381239285, 5666.320041595035, 18638.16128274231, 18768.24927002932, 18839.340623078126, 19017.14422850975, 19095.331663391713, 18466.0, 19094.809051909582, 19137.2345106197, 19272.614547708967, 19320.550958678155, 8832.91167088321, 9060.003399626035, 9817.460039697886, 9850.11348978581, 9873.851892221623, 7761.512960817909, 7926.652469080155, 8171.591420428836, 8346.0, 8372.94862758628, 16668.66662666764, 16770.748438773673, 17263.741043884602, 17279.481615551555, 17328.826711733225, 16597.668046639243, 16924.57688557796, 17576.91211543934, 17687.55781105431, 17897.37359192422, 10974.231803773659, 11085.889141108486, 11110.888911108825, 11167.939045790637, 11339.433028348714, 12261.69345766363, 12500.812422819818, 12503.187292825936, 12522.561710340095, 12641.557545485864, 9838.311318207656, 9852.211823053995, 10225.386476811414, 10303.587856485656, 10682.412467314372, 9724.416535007922, 9766.46284454362, 9810.264230182622, 10031.047050530187, 10282.920293369089, 9147.908520914705, 9162.67930622425, 9173.724788256362, 9304.488253146143, 9490.28822838276, 6230.7507699691505, 6588.912829383098, 6709.0, 7019.894701579583, 7069.787906362817, 6764.391204791593, 6890.689918953559, 7091.964540177266, 7325.670344834389, 7338.192798792022], "value": 6202.04043801319, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.61347946409836, 448.4170578248276, 459.1803630519523, 482.0864461844804, 484.2131536253586, 5080.949190508094, 6410.935890641093, 6453.0, 6527.281998980108, 6546.577766226503, 4760.9523904760945, 6133.20268365113, 6227.657478838662, 6323.557350985433, 6341.619502829833, 3832.3485007548784, 3892.8832135035996, 3945.447637330783, 4524.23088075028, 4777.498362671921, 4651.3720647712635, 4701.48283688794, 4825.90348193036, 4878.707277563358, 4893.748875517266, 4346.478422589294, 4663.860084197479, 4718.41019872516, 4742.834000809955, 4775.450823155347, 4550.384613462223, 4653.976730116362, 4708.0, 4755.096531658974, 4777.617790576745, 3847.9230415391535, 4089.20260549193, 4205.495340559152, 4211.241976444238, 4257.701960862741, 5173.8876141629735, 6096.939030609681, 6137.294211165684, 6198.535109866778, 6245.688405434864, 5419.945800541984, 5528.861778455571, 5565.916511252294, 5676.8864622707315, 5721.166818231794, 6675.165604299463, 6697.330266973264, 6877.415419689332, 6889.586624802478, 6927.7228910843505, 4106.1582375612925, 4731.574158325767, 4766.785494652714, 4786.377770889762, 4870.780814863339, 6044.760824031063, 6688.331166883273, 6950.895736563955, 7254.419646428326, 7303.89044164338, 4041.7574945502774, 4803.327534145223, 4876.171050921352, 4953.984433191187, 4975.427825800007, 4112.383142528644, 4136.73111247768, 4964.255361695775, 5017.272495488134, 5080.923786143211, 4667.159911215946, 5611.018071837412, 5650.4349565043985, 5739.139129130664, 5768.653880767168, 6687.963365678328, 6754.763583274562, 6868.244493105754, 6895.965520172367, 6913.619750913748, 1596.2098761113323, 1657.5110342449007, 1695.177838748206, 1706.5136436115647, 1722.2519143069794, 1121.624255874275, 1133.9092872570175, 1156.6240971684235, 1161.4425075963416, 1164.7495788405533, 6204.224471941007, 6215.844603884939, 6345.619262844152, 6414.198225221848, 6515.315891831289, 6908.309169083152, 7021.859562808715, 7066.646667666596, 7080.672084120455, 7107.92892071083, 5684.403137670568, 5792.739326730307, 6490.643014634146, 6541.649802881126, 6556.63938483388, 3116.9064928052194, 3497.842597083137, 3659.0669379308106, 3671.541057367829, 3713.3316003119157, 7115.505743793861, 7222.0, 7236.963815180996, 7394.482386232912, 7409.110676777387, 5730.140478928194, 6255.218097737783, 6678.966605166943, 6936.271691472423, 7078.858422831515, 6127.632342059491, 7169.737769314949, 7169.749058783022, 7315.4147668186415, 7323.8535229296185, 6883.414909732729, 7033.0, 7043.471739619651, 7104.751333703295, 7106.005159277809, 6027.758889644365, 6738.820706376463, 6860.759873404407, 6908.827279318058, 6952.582845029315, 5466.961277357289, 5577.581681373995, 6541.672916354259, 6840.589564626139, 7125.216226215216, 4770.950390913993, 4773.021530586291, 4834.250691142878, 4880.755962201947, 4940.827071052496, 5539.8892022160135, 6325.557210995186, 7016.228214896255, 7041.788746337618, 7069.222385537484, 5161.870953226199, 5610.297203845443, 6803.795886123424, 6819.0, 6825.965870170618, 5575.916361254665, 6642.667866606747, 6685.933140668531, 6769.661516924036, 6802.217744959195, 5806.0, 6471.223453185459, 6474.676266186578, 6488.383603557654, 6657.667116644052, 6661.367170118831, 6684.63234522106, 6719.764808231688, 6789.253182150058, 6818.829529261808, 5425.376081750649, 6464.353564643498, 6575.638339891352, 6642.136522252076, 6644.169478815148], "value": 4974.255563960854, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.2350267246117, 850.2305413600693, 850.978725531862, 873.2402809555688, 884.747846863644, 14662.78005829912, 17292.567685807862, 17335.21991510382, 17507.29970801168, 17669.116544172804, 13501.662458438544, 14194.858051419482, 14202.928985355085, 14257.0, 14861.628459288524, 699.5977313044993, 958.6261358070324, 974.2157563161652, 1008.2488546033215, 1009.5255230041869, 1004.1013293102669, 1021.9744506387362, 1022.151614160249, 1029.4080903480528, 1044.902852005395, 1055.3140458701828, 1060.194252368202, 1075.9885707435026, 1083.4257843343041, 1089.5478376473793, 946.6355453150518, 1057.444841458233, 1072.2869291920902, 1074.656110044786, 1085.2674444749803, 785.6150486261748, 790.5019837502394, 796.5061661769682, 814.4543156085437, 817.4073796497552, 17111.40110096153, 17219.569510762205, 17580.29678812846, 17667.646647067115, 17905.0, 16404.67190656192, 17408.825911740973, 17637.294508219657, 17738.733918991227, 17814.198361073784, 14590.124592524482, 14609.92695036518, 14792.0, 14920.104793712304, 15025.624359390993, 3528.894133176009, 3664.450332450154, 3671.94492082619, 3696.630336966282, 3697.482352470656, 98964.99999999929, 121450.0, 146531.0, 147922.26038869846, 148756.0, 11281.0, 11935.701607459709, 12375.133740638248, 14204.218767967654, 14556.708865822837, 26003.959841606502, 26440.8677956609, 26582.335441614116, 27087.0, 27098.322541936213, 7992.160823113606, 8046.637901294456, 8155.918440815634, 8241.175882411711, 8300.750977470685, 3924.3328634132267, 4046.7976601169826, 4047.919041619151, 4082.081531655354, 4113.814878330482, 3637.363461394298, 4066.6136717011836, 4607.124646317256, 4757.3101900224965, 4758.690685105457, 952.3904700991371, 993.4436715439377, 1008.3899240959114, 1031.515187861719, 1047.6542740895486, 656.8587753632992, 658.9934100659028, 659.2517492645785, 665.8368699668515, 666.4335314982226, 11013.339199647886, 12047.518099276105, 12359.387727565889, 12875.871241287654, 13050.54323098687, 621.8103478439119, 626.0118726389585, 650.1742786660914, 654.5058480847025, 688.0573614148649, 4404.8458303959205, 4845.3767987724505, 5008.873003574239, 5064.772085256172, 5855.912161317584, 132252.0, 133398.33300833628, 137112.62887371, 141303.0, 142051.28974355062, 114589.0, 116869.83130168589, 119234.0, 125992.7400725981, 127739.0, 98762.5061874686, 100050.0, 100896.49551752194, 100990.0, 101905.0, 11734.51557179813, 12297.32364719949, 12660.493580256687, 12690.984721222465, 12744.6813829655, 44220.55779442293, 45154.77422612866, 45571.544284558055, 46141.077178456915, 48386.75806620944, 69068.0, 83413.91465213418, 83512.16487835044, 85792.57103714441, 88424.0, 14645.121292722471, 17313.134343283036, 17686.20412081485, 17764.911175444042, 18119.637607248045, 18917.621647566695, 19209.32767353136, 19847.801521984595, 19853.305134320228, 19879.30422435208, 15705.764413534034, 16244.0, 17812.91093544524, 17926.462206133838, 18120.456386308433, 16009.279582419053, 17155.571110722332, 17744.46766597004, 17806.46580602584, 18146.0, 17465.952042877467, 17468.30126794914, 17546.73679894777, 17615.383461578782, 17878.91060544689, 10869.278330359917, 11746.119041071783, 11769.234999724991, 12091.939540302594, 12297.815532766837, 13864.930675346559, 14114.435422582981, 14120.0, 14362.13827170373, 14686.339114740076], "value": 8835.97790839363, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 106.0, 115.0, 114.0, 116.0, 115.0, 103.0, 115.0, 113.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 147.0, 152.0, 129.0, 120.0, 146.0, 101.0, 180.0, 146.0], "value": 137.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 209.0, 200.0, 199.0, 197.0, 197.0, 198.0, 205.0, 205.0, 203.0], "value": 201.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 84.0, 75.0, 155.0, 83.0, 88.0, 85.0, 80.0, 138.0, 80.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 114.0, 70.0, 70.0, 73.0, 104.0, 68.0, 73.0, 119.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 89.0, 83.0, 121.0, 138.0, 109.0, 94.0, 90.0, 91.0], "value": 100.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 98.0, 96.0, 151.0, 126.0, 92.0, 152.0, 103.0, 154.0], "value": 117.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 91.0, 89.0, 94.0, 92.0, 91.0, 89.0, 93.0, 93.0, 92.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 51.0, 48.0, 51.0, 51.0, 58.0, 81.0, 43.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 56.0, 55.0, 85.0, 89.0, 49.0, 52.0, 74.0, 84.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 69.0, 97.0, 72.0, 90.0, 92.0, 99.0, 106.0, 101.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 130.0, 137.0, 151.0, 150.0, 160.0, 139.0, 143.0, 166.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 178.0, 180.0, 178.0, 179.0, 175.0, 182.0, 187.0, 175.0, 179.0], "value": 179.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 63.0, 65.0, 65.0, 63.0, 54.0, 65.0, 70.0, 94.0], "value": 66.9, "unit": "score"}]}]} 04:41:49 INFO - Return code: 0 04:41:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:41:49 INFO - # TBPL SUCCESS # 04:41:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:41:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:41:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:41:49 INFO - 2017-06-14 04:41:49.566 screenresolution[1916:11659] starting screenresolution argv=screenresolution get 04:41:49 INFO - 2017-06-14 04:41:49.568 screenresolution[1916:11659] Display 0: 1600x1200x32@60 04:41:49 INFO - 2017-06-14 04:41:49.574 screenresolution[1917:11662] starting screenresolution argv=screenresolution list 04:41:49 INFO - Available Modes on Display 0 04:41:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:41:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:41:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:41:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:41:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:41:49 INFO - Intel Iris: 04:41:49 INFO - Chipset Model: Intel Iris 04:41:49 INFO - Type: GPU 04:41:49 INFO - Bus: Built-In 04:41:49 INFO - VRAM (Dynamic, Max): 1536 MB 04:41:49 INFO - Vendor: Intel (0x8086) 04:41:49 INFO - Device ID: 0x0a2e 04:41:49 INFO - Revision ID: 0x0009 04:41:49 INFO - Displays: 04:41:49 INFO - Display: 04:41:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:41:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:41:49 INFO - Display Serial Number: 1600x1200 60 04:41:49 INFO - Main Display: Yes 04:41:49 INFO - Mirror: Off 04:41:49 INFO - Online: Yes 04:41:49 INFO - Rotation: Supported 04:41:49 INFO - Return code: 0 04:41:49 INFO - Running post-action listener: _resource_record_post_action 04:41:49 INFO - [mozharness: 2017-06-14 11:41:49.810773Z] Finished run-tests step (success) 04:41:49 INFO - Running post-run listener: _resource_record_post_run 04:41:49 INFO - Total resource usage - Wall time: 1249s; CPU: 53.0%; Read bytes: 28479488; Write bytes: 469314048; Read time: 271; Write time: 1745 04:41:49 INFO - TinderboxPrint: CPU usage
53.3% 04:41:49 INFO - TinderboxPrint: I/O read bytes / time
28,479,488 / 271 04:41:49 INFO - TinderboxPrint: I/O write bytes / time
469,314,048 / 1,745 04:41:49 INFO - TinderboxPrint: CPU idle
2,334.9 (46.7%) 04:41:49 INFO - TinderboxPrint: CPU system
1,045.9 (20.9%) 04:41:49 INFO - TinderboxPrint: CPU user
1,616.9 (32.4%) 04:41:49 INFO - TinderboxPrint: Swap in / out
175,730,688 / 0 04:41:49 INFO - install - Wall time: 17s; CPU: 54.0%; Read bytes: 138134016; Write bytes: 137758208; Read time: 11501; Write time: 360 04:41:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:41:49 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 28479488; Write bytes: 313111552; Read time: 271; Write time: 1341 04:41:50 INFO - Running post-run listener: _upload_blobber_files 04:41:50 INFO - Blob upload gear active. 04:41:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:41:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:41:52 INFO - (blobuploader) - INFO - Open directory for files ... 04:41:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:41:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:41:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:54 INFO - (blobuploader) - INFO - Done attempting. 04:41:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:41:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:41:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:56 INFO - (blobuploader) - INFO - Done attempting. 04:41:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:41:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:41:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:57 INFO - (blobuploader) - INFO - Done attempting. 04:41:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:41:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:41:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:59 INFO - (blobuploader) - INFO - Done attempting. 04:41:59 INFO - (blobuploader) - INFO - Iteration through files over. 04:41:59 INFO - Return code: 0 04:41:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:41:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:41:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a87a5e0171527022d687fb2821da85a0e63e9e0f734564027b15aaf473218d68a6440fb0cc0f4e15e3a41bf1b4712c2b9327ef20150ae48e38a8a1fb72bd126", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/12340816e62bf79d66249284d993865e6f31c3335b424630b43ee8cad281bee6cdafc34616145b45f081455fdda32343ee0b3da63edffff5560536e4bcb44ec8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/38dcbee79d7042f7b3564e7877535013aee42247b97a84dbf2721b4426e0d7a0ea986921f47f448ef930ed582c112238875e10de11670c5eba940bf971a46a8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d78859373376c0b8b0aecceaddd7c92b2ab23145a040c61cfe170e6f5283471230d71faebdc98e885d00d18d88129d8b20b2abce38532c0a78d38a45cdf115a"} 04:41:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:41:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:41:59 INFO - Contents: 04:41:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a87a5e0171527022d687fb2821da85a0e63e9e0f734564027b15aaf473218d68a6440fb0cc0f4e15e3a41bf1b4712c2b9327ef20150ae48e38a8a1fb72bd126", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/12340816e62bf79d66249284d993865e6f31c3335b424630b43ee8cad281bee6cdafc34616145b45f081455fdda32343ee0b3da63edffff5560536e4bcb44ec8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/38dcbee79d7042f7b3564e7877535013aee42247b97a84dbf2721b4426e0d7a0ea986921f47f448ef930ed582c112238875e10de11670c5eba940bf971a46a8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d78859373376c0b8b0aecceaddd7c92b2ab23145a040c61cfe170e6f5283471230d71faebdc98e885d00d18d88129d8b20b2abce38532c0a78d38a45cdf115a"} 04:41:59 INFO - Running post-run listener: copy_logs_to_upload_dir 04:41:59 INFO - Copying logs to upload dir... 04:41:59 INFO - mkdir: /builds/slave/test/build/upload/logs 04:41:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.846716 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-14 04:42:00.025942) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 04:42:00.029626) ========= 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.8yu64x1wJH/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.kN8EEwA50v/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a87a5e0171527022d687fb2821da85a0e63e9e0f734564027b15aaf473218d68a6440fb0cc0f4e15e3a41bf1b4712c2b9327ef20150ae48e38a8a1fb72bd126", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/12340816e62bf79d66249284d993865e6f31c3335b424630b43ee8cad281bee6cdafc34616145b45f081455fdda32343ee0b3da63edffff5560536e4bcb44ec8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/38dcbee79d7042f7b3564e7877535013aee42247b97a84dbf2721b4426e0d7a0ea986921f47f448ef930ed582c112238875e10de11670c5eba940bf971a46a8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d78859373376c0b8b0aecceaddd7c92b2ab23145a040c61cfe170e6f5283471230d71faebdc98e885d00d18d88129d8b20b2abce38532c0a78d38a45cdf115a"} build_url:https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011057 build_url: 'https://queue.taskcluster.net/v1/task/NZFL2Dn7TfO8nA5WQB7pTw/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a87a5e0171527022d687fb2821da85a0e63e9e0f734564027b15aaf473218d68a6440fb0cc0f4e15e3a41bf1b4712c2b9327ef20150ae48e38a8a1fb72bd126", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/12340816e62bf79d66249284d993865e6f31c3335b424630b43ee8cad281bee6cdafc34616145b45f081455fdda32343ee0b3da63edffff5560536e4bcb44ec8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/38dcbee79d7042f7b3564e7877535013aee42247b97a84dbf2721b4426e0d7a0ea986921f47f448ef930ed582c112238875e10de11670c5eba940bf971a46a8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d78859373376c0b8b0aecceaddd7c92b2ab23145a040c61cfe170e6f5283471230d71faebdc98e885d00d18d88129d8b20b2abce38532c0a78d38a45cdf115a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 04:42:00.063771) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:42:00.064135) ========= 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.8yu64x1wJH/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.kN8EEwA50v/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005222 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 04:42:00.115291) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 04:42:00.115895) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 04:42:00.116518) ========= ========= Total master_lag: 0.18 =========