builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0060 starttime: 1496944168.72 results: success (0) revision: e61060be36424240058f8bef4c5597f401bc8b7e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:28.721953) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:28.722360) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:28.722630) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:28.747172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:28.747508) ========= 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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-08 10:49:28-- 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% 13.8M=0.001s 2017-06-08 10:49:28 (13.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119959 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:28.890958) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:28.891287) ========= 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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.028748 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:28.940992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 10:49:28.941328) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e61060be36424240058f8bef4c5597f401bc8b7e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev e61060be36424240058f8bef4c5597f401bc8b7e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0HyBiHC9cI/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.g6AYE5Xksh/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-08 10:49:29,042 truncating revision to first 12 chars 2017-06-08 10:49:29,042 Setting DEBUG logging. 2017-06-08 10:49:29,042 attempt 1/10 2017-06-08 10:49:29,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e61060be3642?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 10:49:29,878 unpacking tar archive at: mozilla-central-e61060be3642/testing/mozharness/ program finished with exit code 0 elapsedTime=1.123415 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 10:49:30.086319) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:30.086634) ========= script_repo_revision: e61060be36424240058f8bef4c5597f401bc8b7e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:30.087006) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:30.087266) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 10:49:30.105512) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2017-06-08 10:49:30.105857) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0HyBiHC9cI/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.g6AYE5Xksh/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:49:30 INFO - MultiFileLogger online at 20170608 10:49:30 in /builds/slave/test 10:49:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:49:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:49:30 INFO - {'append_to_log': False, 10:49:30 INFO - 'base_work_dir': '/builds/slave/test', 10:49:30 INFO - 'blob_upload_branch': 'Firefox', 10:49:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:49:30 INFO - 'branch': 'Firefox', 10:49:30 INFO - 'buildbot_json_path': 'buildprops.json', 10:49:30 INFO - 'config_files': ('talos/mac_config.py',), 10:49:30 INFO - 'default_actions': ('clobber', 10:49:30 INFO - 'read-buildbot-config', 10:49:30 INFO - 'download-and-extract', 10:49:30 INFO - 'populate-webroot', 10:49:30 INFO - 'create-virtualenv', 10:49:30 INFO - 'install', 10:49:30 INFO - 'setup-mitmproxy', 10:49:30 INFO - 'run-tests'), 10:49:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:49:30 INFO - 'download_minidump_stackwalk': True, 10:49:30 INFO - 'download_symbols': 'ondemand', 10:49:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:49:30 INFO - 'tooltool.py': '/tools/tooltool.py', 10:49:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:49:30 INFO - '/tools/misc-python/virtualenv.py')}, 10:49:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:49:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:49:30 INFO - 'gecko_profile': False, 10:49:30 INFO - 'gecko_profile_interval': 0, 10:49:30 INFO - 'installer_path': 'installer.exe', 10:49:30 INFO - 'log_level': 'info', 10:49:30 INFO - 'log_name': 'talos', 10:49:30 INFO - 'log_to_console': True, 10:49:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:49:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:49:30 INFO - 'opt_config_files': (), 10:49:30 INFO - 'pip_index': False, 10:49:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:49:30 INFO - 'cmd': ('bash', 10:49:30 INFO - '-c', 10:49:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:49:30 INFO - 'enabled': True, 10:49:30 INFO - 'halt_on_failure': False, 10:49:30 INFO - 'name': 'check_screen_resolution'},), 10:49:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:49:30 INFO - 'cmd': ('bash', 10:49:30 INFO - '-c', 10:49:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:49:30 INFO - 'enabled': True, 10:49:30 INFO - 'halt_on_failure': False, 10:49:30 INFO - 'name': 'check_screen_resolution'},), 10:49:30 INFO - 'run_cmd_checks_enabled': True, 10:49:30 INFO - 'suite': 'dromaeojs-e10s', 10:49:30 INFO - 'system_bits': '32', 10:49:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:49:30 INFO - 'title': 't-yosemite-r7-0060', 10:49:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:49:30 INFO - 'use_talos_json': True, 10:49:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:49:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:49:30 INFO - 'work_dir': 'build'} 10:49:30 INFO - [mozharness: 2017-06-08 17:49:30.313733Z] Running clobber step. 10:49:30 INFO - Running pre-action listener: _resource_record_pre_action 10:49:30 INFO - Running main action method: clobber 10:49:30 INFO - rmtree: /builds/slave/test/build 10:49:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:49:32 INFO - Running post-action listener: _resource_record_post_action 10:49:32 INFO - [mozharness: 2017-06-08 17:49:32.919084Z] Finished clobber step (success) 10:49:32 INFO - [mozharness: 2017-06-08 17:49:32.919219Z] Running read-buildbot-config step. 10:49:32 INFO - Running pre-action listener: _resource_record_pre_action 10:49:32 INFO - Running main action method: read_buildbot_config 10:49:32 INFO - Using buildbot properties: 10:49:32 INFO - { 10:49:32 INFO - "project": "", 10:49:32 INFO - "product": "firefox", 10:49:32 INFO - "who": "ffxbld@noreply.mozilla.org", 10:49:32 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:49:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:49:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:49:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:49:32 INFO - "stage_platform": "macosx64", 10:49:32 INFO - "basedir": "/builds/slave/test", 10:49:32 INFO - "buildnumber": 180, 10:49:32 INFO - "platform": "yosemite_r7", 10:49:32 INFO - "parent_task_id": "eRzT7t3nRvGOO6-JmuD3Pg", 10:49:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:49:32 INFO - "slavebuilddir": "test", 10:49:32 INFO - "taskId": "b4ApIjCoRLqdbSrEJgt4_g", 10:49:32 INFO - "branch": "mozilla-central", 10:49:32 INFO - "script_repo_revision": "production", 10:49:32 INFO - "revision": "e61060be36424240058f8bef4c5597f401bc8b7e", 10:49:32 INFO - "slavename": "t-yosemite-r7-0060", 10:49:32 INFO - "repo_path": "mozilla-central" 10:49:32 INFO - } 10:49:32 INFO - Finding installer, test and symbols from parent task. 10:49:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b4ApIjCoRLqdbSrEJgt4_g'}, attempt #1 10:49:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b4ApIjCoRLqdbSrEJgt4_g'}, attempt #1 10:49:33 INFO - Task dependencies: c4mw-RfwQViHOtcj5IEZJQ eRzT7t3nRvGOO6-JmuD3Pg 10:49:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg'}, attempt #1 10:49:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:49:34 INFO - Set test_url: https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/test_packages.json 10:49:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/target.crashreporter-symbols.zip 10:49:34 INFO - Running post-action listener: _resource_record_post_action 10:49:34 INFO - [mozharness: 2017-06-08 17:49:34.313855Z] Finished read-buildbot-config step (success) 10:49:34 INFO - [mozharness: 2017-06-08 17:49:34.313974Z] Running download-and-extract step. 10:49:34 INFO - Running pre-action listener: _resource_record_pre_action 10:49:34 INFO - Running main action method: download_and_extract 10:49:34 INFO - mkdir: /builds/slave/test/build/tests 10:49: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')]} 10:49:34 INFO - trying https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:49:34 INFO - Downloading https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:49:35 INFO - Downloaded 1328 bytes. 10:49:35 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:49:35 INFO - Using the following test package requirements: 10:49:35 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:49:35 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:49:35 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:49:35 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:49:35 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'jsshell-mac.zip'], 10:49:35 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:49:35 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:49:35 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:49:35 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:49:35 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 10:49:35 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:49:35 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:49:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:49:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:49:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:49:35 INFO - Fetch https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:49:37 INFO - Content-Length response header: 59539888 10:49:37 INFO - Bytes received: 59539888 10:49:55 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:49:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:49:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:49:55 INFO - Fetch https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:49:57 INFO - Content-Length response header: 59539888 10:49:57 INFO - Bytes received: 59539888 10:50:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:50:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:50:15 INFO - Fetch https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:50:16 INFO - Content-Length response header: 14447568 10:50:16 INFO - Bytes received: 14447568 10:50: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')]} 10:50:17 INFO - trying https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:50:17 INFO - Downloading https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:50:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:50:18 INFO - Downloaded 68133801 bytes. 10:50:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:50:18 INFO - mkdir: /builds/slave/test/properties 10:50:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:50:18 INFO - Writing to file /builds/slave/test/properties/build_url 10:50:18 INFO - Contents: 10:50:18 INFO - build_url:https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:50:18 INFO - Running post-action listener: _resource_record_post_action 10:50:18 INFO - Running post-action listener: set_extra_try_arguments 10:50:18 INFO - [mozharness: 2017-06-08 17:50:18.970087Z] Finished download-and-extract step (success) 10:50:18 INFO - [mozharness: 2017-06-08 17:50:18.970245Z] Running populate-webroot step. 10:50:18 INFO - Running pre-action listener: _resource_record_pre_action 10:50:18 INFO - Running main action method: populate_webroot 10:50:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:50:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:50:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:50:18 INFO - '--tppagecycles', 10:50:18 INFO - '7'], 10:50:18 INFO - 'tests': ['tsvgm']}}, 10:50:18 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:50:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:50:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:50:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:50:18 INFO - 'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:50:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:50:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:50:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:50:18 INFO - 'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['damp', 'tps']}, 10:50:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:50:18 INFO - 'tests': ['damp', 'tps']}, 10:50:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['dromaeo_dom']}, 10:50:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:50:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:50:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:50:18 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:50:18 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:50:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['a11yr', 10:50:18 INFO - 'ts_paint', 10:50:18 INFO - 'tpaint', 10:50:18 INFO - 'sessionrestore', 10:50:18 INFO - 'sessionrestore_no_auto_restore', 10:50:18 INFO - 'tabpaint']}, 10:50:18 INFO - 'other-e10s': {'tests': ['a11yr', 10:50:18 INFO - 'ts_paint', 10:50:18 INFO - 'tpaint', 10:50:18 INFO - 'sessionrestore', 10:50:18 INFO - 'sessionrestore_no_auto_restore', 10:50:18 INFO - 'tabpaint']}, 10:50:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:50:18 INFO - 'ts_paint', 10:50:18 INFO - 'tpaint', 10:50:18 INFO - 'sessionrestore', 10:50:18 INFO - 'sessionrestore_no_auto_restore', 10:50:18 INFO - 'tabpaint']}, 10:50:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:50:18 INFO - 'ts_paint', 10:50:18 INFO - 'tpaint', 10:50:18 INFO - 'sessionrestore', 10:50:18 INFO - 'sessionrestore_no_auto_restore', 10:50:18 INFO - 'tabpaint']}, 10:50:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['a11yr', 10:50:18 INFO - 'ts_paint', 10:50:18 INFO - 'tpaint', 10:50:18 INFO - 'sessionrestore', 10:50:18 INFO - 'sessionrestore_no_auto_restore', 10:50:18 INFO - 'tabpaint']}, 10:50:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['a11yr', 10:50:18 INFO - 'ts_paint', 10:50:18 INFO - 'tpaint', 10:50:18 INFO - 'sessionrestore', 10:50:18 INFO - 'sessionrestore_no_auto_restore', 10:50:18 INFO - 'tabpaint']}, 10:50:18 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:50:18 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:50:18 INFO - 'bloom_basic_ref']}, 10:50:18 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 10:50:18 INFO - 'talos_options': ['--mitmproxy', 10:50:18 INFO - 'mitmproxy-recording-1.mp', 10:50:18 INFO - '--firstNonBlankPaint'], 10:50:18 INFO - 'tests': ['Quantum_1']}, 10:50:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['tsvgx', 10:50:18 INFO - 'tsvgr_opacity', 10:50:18 INFO - 'tart', 10:50:18 INFO - 'tscrollx', 10:50:18 INFO - 'cart', 10:50:18 INFO - 'tsvg_static']}, 10:50:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:50:18 INFO - 'tsvgr_opacity', 10:50:18 INFO - 'tart', 10:50:18 INFO - 'tscrollx', 10:50:18 INFO - 'cart', 10:50:18 INFO - 'tsvg_static']}, 10:50:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:50:18 INFO - 'talos_options': ['--disable-e10s'], 10:50:18 INFO - 'tests': ['tp5o']}, 10:50:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:50:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:50:18 INFO - 'talos_options': ['--disable-e10s', 10:50:18 INFO - '--xperf_path', 10:50:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:50:18 INFO - 'tests': ['tp5n']}, 10:50:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:50:18 INFO - 'talos_options': ['--xperf_path', 10:50:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:50:18 INFO - 'tests': ['tp5n']}}, 10:50:18 INFO - 'talos.zip': {'path': '', 10:50:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:50:18 INFO - Running post-action listener: _resource_record_post_action 10:50:18 INFO - [mozharness: 2017-06-08 17:50:18.993090Z] Finished populate-webroot step (success) 10:50:18 INFO - [mozharness: 2017-06-08 17:50:18.993260Z] Running create-virtualenv step. 10:50:18 INFO - Running pre-action listener: _resource_record_pre_action 10:50:18 INFO - Running main action method: create_virtualenv 10:50:18 INFO - Creating virtualenv /builds/slave/test/build/venv 10:50:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:50:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:50:18 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:50:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:50:19 INFO - Using real prefix '/tools/python27' 10:50:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:50:19 INFO - Installing distribute.............................................................................................................................................................................................done. 10:50:22 INFO - Installing pip.................done. 10:50:22 INFO - Return code: 0 10:50:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:50:23 INFO - Reading from file tmpfile_stdout 10:50:23 INFO - Output received: 10:50:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:50:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:23 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:50:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:50:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:23 INFO - 'HOME': '/Users/cltbld', 10:50:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:23 INFO - 'LOGNAME': 'cltbld', 10:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:23 INFO - 'MOZ_NO_REMOTE': '1', 10:50:23 INFO - 'NO_EM_RESTART': '1', 10:50:23 INFO - 'PAGER': '/bin/cat', 10:50:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:23 INFO - 'PWD': '/builds/slave/test', 10:50:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:23 INFO - 'SHELL': '/bin/bash', 10:50:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:23 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:23 INFO - 'USER': 'cltbld', 10:50:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:23 INFO - 'XPC_FLAGS': '0x0', 10:50:23 INFO - 'XPC_SERVICE_NAME': '0', 10:50:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:50:23 INFO - Cleaning up... 10:50:23 INFO - Return code: 0 10:50:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:23 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:50:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:50:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:23 INFO - 'HOME': '/Users/cltbld', 10:50:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:23 INFO - 'LOGNAME': 'cltbld', 10:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:23 INFO - 'MOZ_NO_REMOTE': '1', 10:50:23 INFO - 'NO_EM_RESTART': '1', 10:50:23 INFO - 'PAGER': '/bin/cat', 10:50:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:23 INFO - 'PWD': '/builds/slave/test', 10:50:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:23 INFO - 'SHELL': '/bin/bash', 10:50:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:23 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:23 INFO - 'USER': 'cltbld', 10:50:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:23 INFO - 'XPC_FLAGS': '0x0', 10:50:23 INFO - 'XPC_SERVICE_NAME': '0', 10:50:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:23 INFO - Downloading/unpacking psutil>=3.1.1 10:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:50:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:50:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:50:25 INFO - Installing collected packages: psutil 10:50:25 INFO - Running setup.py install for psutil 10:50:25 INFO - building 'psutil._psutil_osx' extension 10:50:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:50:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:50:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:50:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:50:26 INFO - building 'psutil._psutil_posix' extension 10:50:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:50:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:50:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:50:26 INFO - ^ 10:50:26 INFO - 1 warning generated. 10:50:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:50:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:50:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:50:26 INFO - Successfully installed psutil 10:50:26 INFO - Cleaning up... 10:50:26 INFO - Return code: 0 10:50:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:27 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:50:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:27 INFO - 'HOME': '/Users/cltbld', 10:50:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:27 INFO - 'LOGNAME': 'cltbld', 10:50:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:27 INFO - 'MOZ_NO_REMOTE': '1', 10:50:27 INFO - 'NO_EM_RESTART': '1', 10:50:27 INFO - 'PAGER': '/bin/cat', 10:50:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:27 INFO - 'PWD': '/builds/slave/test', 10:50:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:27 INFO - 'SHELL': '/bin/bash', 10:50:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:27 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:27 INFO - 'USER': 'cltbld', 10:50:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:27 INFO - 'XPC_FLAGS': '0x0', 10:50:27 INFO - 'XPC_SERVICE_NAME': '0', 10:50:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:50:29 INFO - Installing collected packages: mozsystemmonitor 10:50:29 INFO - Running setup.py install for mozsystemmonitor 10:50:29 INFO - Successfully installed mozsystemmonitor 10:50:29 INFO - Cleaning up... 10:50:29 INFO - Return code: 0 10:50:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:29 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:29 INFO - 'HOME': '/Users/cltbld', 10:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:29 INFO - 'LOGNAME': 'cltbld', 10:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:29 INFO - 'MOZ_NO_REMOTE': '1', 10:50:29 INFO - 'NO_EM_RESTART': '1', 10:50:29 INFO - 'PAGER': '/bin/cat', 10:50:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:29 INFO - 'PWD': '/builds/slave/test', 10:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:29 INFO - 'SHELL': '/bin/bash', 10:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:29 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:29 INFO - 'USER': 'cltbld', 10:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:29 INFO - 'XPC_FLAGS': '0x0', 10:50:29 INFO - 'XPC_SERVICE_NAME': '0', 10:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:30 INFO - Downloading/unpacking jsonschema==2.5.1 10:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:50:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:31 INFO - Downloading functools32-3.2.3-2.tar.gz 10:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:50:31 INFO - warning: no files found matching '*.txt' 10:50:31 INFO - no previously-included directories found matching 'build' 10:50:31 INFO - no previously-included directories found matching 'dist' 10:50:31 INFO - no previously-included directories found matching '.git*' 10:50:32 INFO - Installing collected packages: jsonschema, functools32 10:50:32 INFO - Running setup.py install for functools32 10:50:32 INFO - warning: no files found matching '*.txt' 10:50:32 INFO - no previously-included directories found matching 'build' 10:50:32 INFO - no previously-included directories found matching 'dist' 10:50:32 INFO - no previously-included directories found matching '.git*' 10:50:32 INFO - Successfully installed jsonschema functools32 10:50:32 INFO - Cleaning up... 10:50:32 INFO - Return code: 0 10:50:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:32 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:32 INFO - 'HOME': '/Users/cltbld', 10:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:32 INFO - 'LOGNAME': 'cltbld', 10:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:32 INFO - 'MOZ_NO_REMOTE': '1', 10:50:32 INFO - 'NO_EM_RESTART': '1', 10:50:32 INFO - 'PAGER': '/bin/cat', 10:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:32 INFO - 'PWD': '/builds/slave/test', 10:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:32 INFO - 'SHELL': '/bin/bash', 10:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:32 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:32 INFO - 'USER': 'cltbld', 10:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:32 INFO - 'XPC_FLAGS': '0x0', 10:50:32 INFO - 'XPC_SERVICE_NAME': '0', 10:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:50:32 INFO - Cleaning up... 10:50:32 INFO - Return code: 0 10:50:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:32 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:32 INFO - 'HOME': '/Users/cltbld', 10:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:32 INFO - 'LOGNAME': 'cltbld', 10:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:32 INFO - 'MOZ_NO_REMOTE': '1', 10:50:32 INFO - 'NO_EM_RESTART': '1', 10:50:32 INFO - 'PAGER': '/bin/cat', 10:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:32 INFO - 'PWD': '/builds/slave/test', 10:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:32 INFO - 'SHELL': '/bin/bash', 10:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:32 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:32 INFO - 'USER': 'cltbld', 10:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:32 INFO - 'XPC_FLAGS': '0x0', 10:50:32 INFO - 'XPC_SERVICE_NAME': '0', 10:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:32 INFO - Downloading/unpacking blobuploader==1.2.4 10:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:34 INFO - Downloading blobuploader-1.2.4.tar.gz 10:50:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:50:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:50:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:34 INFO - Downloading docopt-0.6.1.tar.gz 10:50:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:50:35 INFO - Installing collected packages: blobuploader, requests, docopt 10:50:35 INFO - Running setup.py install for blobuploader 10:50:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:50:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:50:35 INFO - Running setup.py install for requests 10:50:35 INFO - Running setup.py install for docopt 10:50:35 INFO - Successfully installed blobuploader requests docopt 10:50:35 INFO - Cleaning up... 10:50:35 INFO - Return code: 0 10:50:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:50: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')]} 10:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50: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')]} 10:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:36 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:50:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:36 INFO - 'HOME': '/Users/cltbld', 10:50:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:36 INFO - 'LOGNAME': 'cltbld', 10:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:36 INFO - 'MOZ_NO_REMOTE': '1', 10:50:36 INFO - 'NO_EM_RESTART': '1', 10:50:36 INFO - 'PAGER': '/bin/cat', 10:50:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:36 INFO - 'PWD': '/builds/slave/test', 10:50:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:36 INFO - 'SHELL': '/bin/bash', 10:50:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:36 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:36 INFO - 'USER': 'cltbld', 10:50:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:36 INFO - 'XPC_FLAGS': '0x0', 10:50:36 INFO - 'XPC_SERVICE_NAME': '0', 10:50:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:50:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-tnme8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:50:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-PT_3fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:50:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-9xDdCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:50:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-wH2E5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:50:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-NIOBH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:50:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-QrqVrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:50:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-d1NShD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:50:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-XjgANF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-fnNQ7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-BoCI8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-f4qspF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-yuuFUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-HsEAFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-SVkrIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-SpZM5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-MkC2Gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:50:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-JdD19I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:50:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:50:38 INFO - Running setup.py install for manifestparser 10:50:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:50:38 INFO - Running setup.py install for mozcrash 10:50:38 INFO - Running setup.py install for mozdebug 10:50:38 INFO - Running setup.py install for mozdevice 10:50:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:50:38 INFO - Running setup.py install for mozfile 10:50:38 INFO - Running setup.py install for mozhttpd 10:50:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:50:38 INFO - Running setup.py install for mozinfo 10:50:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Running setup.py install for mozInstall 10:50:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Running setup.py install for mozleak 10:50:39 INFO - Running setup.py install for mozlog 10:50:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Running setup.py install for moznetwork 10:50:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Running setup.py install for mozprocess 10:50:39 INFO - Running setup.py install for mozprofile 10:50:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:50:39 INFO - Running setup.py install for mozrunner 10:50:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:50:40 INFO - Running setup.py install for mozscreenshot 10:50:40 INFO - Running setup.py install for moztest 10:50:40 INFO - Running setup.py install for mozversion 10:50:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:50:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:50:40 INFO - Cleaning up... 10:50:40 INFO - Return code: 0 10:50:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:40 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:50:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:40 INFO - 'HOME': '/Users/cltbld', 10:50:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:40 INFO - 'LOGNAME': 'cltbld', 10:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:40 INFO - 'MOZ_NO_REMOTE': '1', 10:50:40 INFO - 'NO_EM_RESTART': '1', 10:50:40 INFO - 'PAGER': '/bin/cat', 10:50:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:40 INFO - 'PWD': '/builds/slave/test', 10:50:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:40 INFO - 'SHELL': '/bin/bash', 10:50:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:40 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:40 INFO - 'USER': 'cltbld', 10:50:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:40 INFO - 'XPC_FLAGS': '0x0', 10:50:40 INFO - 'XPC_SERVICE_NAME': '0', 10:50:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:50:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-ylgRin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:50:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-CJ0mty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-yoH7py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-EwtjE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-Vshilv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-k1_E5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-wv4obs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:50:41 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)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-iEjy7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-iS5Pj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-hwyigi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:50:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-_6fGpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:50:42 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-DLRQaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:50:42 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-WhmVCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:50:42 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-wQDmxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:42 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-EpN6rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:50:42 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-I5Xvy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:50:42 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-qN3y9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:50:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:50: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 10:50: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 10:50: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 10:50: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 10:50:44 INFO - Downloading blessings-1.6.tar.gz 10:50:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:50:44 INFO - Installing collected packages: blessings 10:50:44 INFO - Running setup.py install for blessings 10:50:44 INFO - Successfully installed blessings 10:50:44 INFO - Cleaning up... 10:50:44 INFO - Return code: 0 10:50:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:50:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:50:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:50:44 INFO - Reading from file tmpfile_stdout 10:50:44 INFO - Current package versions: 10:50:44 INFO - blessings == 1.6 10:50:44 INFO - blobuploader == 1.2.4 10:50:44 INFO - docopt == 0.6.1 10:50:44 INFO - functools32 == 3.2.3-2 10:50:44 INFO - jsonschema == 2.5.1 10:50:44 INFO - manifestparser == 1.1 10:50:44 INFO - mozInstall == 1.12 10:50:44 INFO - mozcrash == 1.0 10:50:44 INFO - mozdebug == 0.1 10:50:44 INFO - mozdevice == 0.51 10:50:44 INFO - mozfile == 1.2 10:50:44 INFO - mozhttpd == 0.7 10:50:44 INFO - mozinfo == 0.10 10:50:44 INFO - mozleak == 0.1 10:50:44 INFO - mozlog == 3.4 10:50:44 INFO - moznetwork == 0.27 10:50:44 INFO - mozprocess == 0.25 10:50:44 INFO - mozprofile == 0.28 10:50:44 INFO - mozrunner == 6.13 10:50:44 INFO - mozscreenshot == 0.1 10:50:44 INFO - mozsystemmonitor == 0.3 10:50:44 INFO - moztest == 0.8 10:50:44 INFO - mozversion == 1.4 10:50:44 INFO - psutil == 3.1.1 10:50:44 INFO - requests == 1.2.3 10:50:44 INFO - wsgiref == 0.1.2 10:50:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:50:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:44 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:50:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:50:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:44 INFO - 'HOME': '/Users/cltbld', 10:50:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:44 INFO - 'LOGNAME': 'cltbld', 10:50:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:44 INFO - 'MOZ_NO_REMOTE': '1', 10:50:44 INFO - 'NO_EM_RESTART': '1', 10:50:44 INFO - 'PAGER': '/bin/cat', 10:50:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:44 INFO - 'PWD': '/builds/slave/test', 10:50:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:44 INFO - 'SHELL': '/bin/bash', 10:50:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:44 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:44 INFO - 'USER': 'cltbld', 10:50:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:44 INFO - 'XPC_FLAGS': '0x0', 10:50:44 INFO - 'XPC_SERVICE_NAME': '0', 10:50:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:50:44 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:50: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 10:50: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 10:50: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 10:50: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 10:50:46 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:50:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:50:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:50:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:50:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:50:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:50:46 INFO - Installing collected packages: marionette-driver 10:50:46 INFO - Running setup.py install for marionette-driver 10:50:46 INFO - Successfully installed marionette-driver 10:50:46 INFO - Cleaning up... 10:50:46 INFO - Return code: 0 10:50:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:50: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')]} 10:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50: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')]} 10:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:46 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 0x10dc44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe01bd7e220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, '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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:50:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:50:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:50:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:50:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:46 INFO - 'HOME': '/Users/cltbld', 10:50:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:46 INFO - 'LOGNAME': 'cltbld', 10:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:46 INFO - 'MOZ_NO_REMOTE': '1', 10:50:46 INFO - 'NO_EM_RESTART': '1', 10:50:46 INFO - 'PAGER': '/bin/cat', 10:50:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:46 INFO - 'PWD': '/builds/slave/test', 10:50:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:46 INFO - 'SHELL': '/bin/bash', 10:50:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:50:46 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:50:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:46 INFO - 'USER': 'cltbld', 10:50:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:46 INFO - 'XPC_FLAGS': '0x0', 10:50:46 INFO - 'XPC_SERVICE_NAME': '0', 10:50:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:50:46 INFO - Cleaning up... 10:50:46 INFO - Return code: 0 10:50:46 INFO - Running post-action listener: _resource_record_post_action 10:50:46 INFO - Running post-action listener: _start_resource_monitoring 10:50:46 INFO - Starting resource monitoring. 10:50:46 INFO - [mozharness: 2017-06-08 17:50:46.978624Z] Finished create-virtualenv step (success) 10:50:46 INFO - [mozharness: 2017-06-08 17:50:46.979598Z] Running install step. 10:50:46 INFO - Running pre-action listener: _resource_record_pre_action 10:50:46 INFO - Running main action method: install 10:50:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:50:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:50:47 INFO - Reading from file tmpfile_stdout 10:50:47 INFO - Detecting whether we're running mozinstall >=1.0... 10:50:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:50:47 INFO - Reading from file tmpfile_stdout 10:50:47 INFO - Output received: 10:50:47 INFO - Usage: mozinstall [options] installer 10:50:47 INFO - Options: 10:50:47 INFO - -h, --help show this help message and exit 10:50:47 INFO - -d DEST, --destination=DEST 10:50:47 INFO - Directory to install application into. [default: 10:50:47 INFO - "/builds/slave/test"] 10:50:47 INFO - --app=APP Application being installed. [default: firefox] 10:50:47 INFO - mkdir: /builds/slave/test/build/application 10:50:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:51:09 INFO - Reading from file tmpfile_stdout 10:51:09 INFO - Output received: 10:51:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:51:09 INFO - Running post-action listener: _resource_record_post_action 10:51:09 INFO - [mozharness: 2017-06-08 17:51:09.003207Z] Finished install step (success) 10:51:09 INFO - [mozharness: 2017-06-08 17:51:09.003312Z] Running setup-mitmproxy step. 10:51:09 INFO - Running pre-action listener: _resource_record_pre_action 10:51:09 INFO - Running main action method: setup_mitmproxy 10:51:09 INFO - Skipping: mitmproxy is not required 10:51:09 INFO - Running post-action listener: _resource_record_post_action 10:51:09 INFO - [mozharness: 2017-06-08 17:51:09.004010Z] Finished setup-mitmproxy step (success) 10:51:09 INFO - [mozharness: 2017-06-08 17:51:09.004127Z] Running run-tests step. 10:51:09 INFO - Running pre-action listener: _resource_record_pre_action 10:51:09 INFO - Running main action method: run_tests 10:51:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:51:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:51:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:51:09 INFO - 2017-06-08 10:51:09.017 screenresolution[1626:7389] starting screenresolution argv=screenresolution get 10:51:09 INFO - 2017-06-08 10:51:09.020 screenresolution[1626:7389] Display 0: 1600x1200x32@60 10:51:09 INFO - 2017-06-08 10:51:09.025 screenresolution[1627:7391] starting screenresolution argv=screenresolution list 10:51:09 INFO - Available Modes on Display 0 10:51:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:51:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:51:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:51:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:51:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:51:09 INFO - Intel Iris: 10:51:09 INFO - Chipset Model: Intel Iris 10:51:09 INFO - Type: GPU 10:51:09 INFO - Bus: Built-In 10:51:09 INFO - VRAM (Dynamic, Max): 1536 MB 10:51:09 INFO - Vendor: Intel (0x8086) 10:51:09 INFO - Device ID: 0x0a2e 10:51:09 INFO - Revision ID: 0x0009 10:51:09 INFO - Displays: 10:51:09 INFO - Display: 10:51:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:51:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:51:09 INFO - Display Serial Number: 1600x1200 60 10:51:09 INFO - Main Display: Yes 10:51:09 INFO - Mirror: Off 10:51:09 INFO - Online: Yes 10:51:09 INFO - Rotation: Supported 10:51:09 INFO - Return code: 0 10:51:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:51:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:51:09 INFO - Python 2.7.3 10:51:09 INFO - Return code: 0 10:51:09 INFO - grabbing minidump binary from tooltool 10:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:09 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 0x10d99ad30>, '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 0x10d964cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d98bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:51:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:51:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:51:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:51:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:51:09 INFO - Return code: 0 10:51:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:51:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:51:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:51:09 INFO - ENV: RUST_BACKTRACE is now 1 10:51:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:51:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:51:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:51:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0060 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:51:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0HyBiHC9cI/Render', 10:51:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:09 INFO - 'HOME': '/Users/cltbld', 10:51:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:09 INFO - 'LOGNAME': 'cltbld', 10:51:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:51:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:09 INFO - 'MOZ_NO_REMOTE': '1', 10:51:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:51:09 INFO - 'NO_EM_RESTART': '1', 10:51:09 INFO - 'PAGER': '/bin/cat', 10:51:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:09 INFO - 'PWD': '/builds/slave/test', 10:51:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:51:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:09 INFO - 'RUST_BACKTRACE': '1', 10:51:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:51:09 INFO - 'SHELL': '/bin/bash', 10:51:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g6AYE5Xksh/Listeners', 10:51:09 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 10:51:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:09 INFO - 'USER': 'cltbld', 10:51:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:09 INFO - 'XPC_FLAGS': '0x0', 10:51:09 INFO - 'XPC_SERVICE_NAME': '0', 10:51:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:51:09 INFO - mozversion application_buildid: 20170608093045 10:51:09 INFO - mozversion application_changeset: e61060be36424240058f8bef4c5597f401bc8b7e 10:51:09 INFO - mozversion application_display_name: Nightly 10:51:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:51:09 INFO - mozversion application_name: Firefox 10:51:09 INFO - mozversion application_remotingname: firefox 10:51:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:51:09 INFO - mozversion application_vendor: Mozilla 10:51:09 INFO - mozversion application_version: 55.0a1 10:51:09 INFO - mozversion platform_buildid: 20170608093045 10:51:09 INFO - mozversion platform_changeset: e61060be36424240058f8bef4c5597f401bc8b7e 10:51:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:51:09 INFO - mozversion platform_version: 55.0a1 10:51:09 INFO - using testdate: 1496944269 10:51:09 INFO - actual date: 1496944269 10:51:09 INFO - starting webserver on 'localhost:49228' 10:51:09 INFO - SUITE-START | Running 2 tests 10:51:09 INFO - TEST-START | dromaeo_css 10:51:09 INFO - operating with platform_type : mac_ 10:51:09 INFO - Initialising browser for dromaeo_css test... 10:51:09 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpbQROmU/profile 10:51:09 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 10:51:43 INFO - TEST-INFO | 1637: killed by SIGTERM 10:51:43 INFO - Browser initialized. 10:51:43 INFO - Running cycle 1/1 for dromaeo_css test... 10:51:43 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpbQROmU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:51:45 INFO - PID 1647 | 2017-06-08 10:51:45.803 plugin-container[1648:7966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 10:51:45 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:51:45 INFO - PID 1647 | 2017-06-08 10:51:45.804 plugin-container[1648:7966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 10:51:45 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:51:45 INFO - PID 1647 | 2017-06-08 10:51:45.804 plugin-container[1648:7966] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:51:46 INFO - PID 1647 | 2017-06-08 10:51:46.262 plugin-container[1649:8013] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:51:46 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:51:46 INFO - PID 1647 | 2017-06-08 10:51:46.262 plugin-container[1649:8013] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 10:51:46 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:51:46 INFO - PID 1647 | 2017-06-08 10:51:46.262 plugin-container[1649:8013] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:51:55 INFO - PID 1647 | RSS: Main: 252428288 10:51:55 INFO - PID 1647 | 10:54:59 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 10:55:01 INFO - PID 1647 | RSS: Main: 269602816 10:55:01 INFO - PID 1647 | 10:58:03 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 10:58:03 INFO - PID 1647 | RSS: Main: 278953984 10:58:03 INFO - PID 1647 | 11:01:05 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 11:01:05 INFO - PID 1647 | RSS: Main: 282578944 11:01:05 INFO - PID 1647 | 11:04:07 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 11:04:08 INFO - PID 1647 | RSS: Main: 284733440 11:04:08 INFO - PID 1647 | 11:07:11 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 11:07:12 INFO - PID 1647 | RSS: Main: 286474240 11:07:12 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 11:10:13 INFO - PID 1647 | RSS: Main: 284319744 11:10:13 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | __start_tp_report 11:10:13 INFO - PID 1647 | _x_x_mozilla_page_load 11:10:13 INFO - PID 1647 | _x_x_mozilla_page_load_details 11:10:13 INFO - PID 1647 | |i|pagename|runs| 11:10:13 INFO - PID 1647 | |0;dojo.html;2948.3661012882226;3529.1177205698573;3535.186907011389;3634.745567810253;3657.2685462907425;5091.490850914916;6188.443040126388;6217;6290.685465726707;6324.525660575458;6276.215473065867;6281.717322720478;6292.182016337881;6298.968505157479;6305.896468118084;5426.375966763833;5450.182472629108;5454.59090568207;5467.933752918182;5583.246261754672;5504.091824848895;5523.171524271352;5528.612997090185;5538.113901775717;5545.833624991256;5505.3393592768925;5522.889542209154;5561.221428999943;5563.221149039137;5564;5511.9724401377935;5522.66863988162;5529.806456773994;5556.805511807108;5566.888662226774;5404.4595540446035;5444.646098003617;5501.229827824108;5521.641093328921;5529.695866727328;6317.968410157966;6330.68346582669;6356.110144579762;6406.23125224976;6428.517861160385;6616.172978377703;6724.260331363546;6726.663666816639;6850.451963842881;6862;6153.938460615426;6774.593524388503;6795.558288711218;6796.558223715443;6797.354251346114;5704.458076482728;6345.07996340528;6345.714442850082;6361.632249066427;6365.522585806083;5399.305483526955;6985.545939513915;7069.646517674096;7081.398081163055;7082.929170708227;6499.837504062341;6659.001149827468;6679.833004174934;6703.363180497845;6704.161979752531;6690.765823196165;6892.207396149507;6907.481938854606;6910.170779506491;6915.135608048994;6650.501212409089;6657.1345725055435;6674.733010679615;6678.766243181465;6686.76294885438;4947.5794557462295;6827.21487028998;6840.760573379908;6848.075509806113;6859.17689877208;5330.653507521999;5358.678479291255;5373.408925018245;5410.702411367412;5452.154915988028;4760.523947605212;4791.257355109963;4796.9760151199025;4819.012102518976;4827.637927155477;6231.875362492816;6313;6341.460975817107;6346.460550853137;6417.8716425672155;6820.386165245052;6827.57621044532;6832.521723479309;6836.897446538306;6842.110525631636;5345.251664766935;6193.102000209944;6353.110564520971;6416.967915160394;6434.871302573923;4921.87695307613;5897.3807750185615;5948.791792287336;5968.7015649217365;6058.969705151446;6904.378605925491;6916.585004899722;6923.169219693669;6925.134358205224;6932.6533673316135;6773.950037744157;6774.898376524257;6785.423239024738;6785.93214067853;6793.898091528532;5067.898642027065;5940.346561878104;6919.965400172967;6926.168859736864;7068.893966590403;5617.662940223599;6031.24609423822;6568.967155164193;6794.4904132189295;6832.350926661959;6957.895631565631;6962.895556566555;6970.745265852195;6997.335253150943;7003.859922801414;6503.089567460464;6611.900821487586;6646.003099535108;6733.42765864907;6765.932340676531;5205.843824685266;5866.266716660418;6583.177102862142;6613.603183808987;6622.668866556556;4825.5898248649255;6534.542582019213;6651.700673469609;6659.467242620675;6663.333666633298;6693.899591506034;6714.664266786739;6751.797446076625;6757.087793147862;6801.761938332134;6339.080833279057;6584.308647591934;6589.341065893564;6596.241432235162;6622.1722284714415 11:10:13 INFO - PID 1647 | |1;ext.html;757.7575175943697;778.9883151752724;823.5017814222397;837.932965362771;1092.7869065532213;25567.232983010515;26342;26351;36470.63529364706;36870.26259474816;38401.42397864034;38785.41821872667;38849.80575097121;38906.02734931628;39044.41433378502;6735.595864248148;8233.588320583976;8237.546934918577;8258.256756891878;8279.213474719892;7995.920040799605;8070.152633973436;8092.919070809276;8101.230383113595;8104.5137291762385;7367.1527774306105;7571.962140189319;8078.959605201966;8124.350051995856;8151.755447336589;8112.351011919032;8154.796130096735;8171.468854524496;8184.263416292562;8187.1403502631965;7900.2889739923685;7941.404394670423;7984.680612775482;7987.281144697005;7994.040715114165;28230.576541351897;34733.652663473295;34740;34813.78151764676;34870.30259394798;3009.368032713133;3512.877049303262;3540.6105328413855;3545.4150065239205;3549.6272891346553;28883.133505994854;29011.274718132005;29013.129606111852;29088.854555727085;29264.853675731698;2994.2364697002345;3053.4656435123766;3054.144839444959;3054.7861649684532;3064.264576501646;245817;251724;252092;292172;295205;37557.248855023296;37625.435618465744;43064;44319.89200269955;46172.307415388794;48461.5153848457;48466.5153348469;48666.75666621644;48725.53823385304;48839.75580122077;13447.394867230998;13515.121517101357;13556.254406007567;13559.101725758403;13571.52499662507;11260.887391126147;11270.323780573191;11293.983541481306;11317.83023254652;11318.94340528292;13098.934505327412;13130.540431084866;13143.07998440119;13145.342732863319;13227.603171904859;5799.811038737049;5861.120831875168;5872.236609240771;5878.147668588031;5891.941080589225;951.6716732727198;953.5280036382036;954.1555723185031;990.8761404824253;1014.4927536231884;19163.65854390207;22198.12002892267;23058.423539411313;23252.65121023152;23833;992.9503524823874;1013.9188864890939;1094.2668412163869;1094.7208461842338;1095.4851219926622;10065.899341006496;11279.830802537968;11519;11850.81491850808;12131.393430328448;316684;322969;326826.3658681691;327352;327374.3631281876;51595.22607160896;51755.741221294404;51786.741066294424;51868.740656296475;51964.22053669198;1330.5476138113088;1455.9126452412888;1468.067776961637;1468.383279022813;1472.212366384001;27407.17778466649;27711;29596.852015739783;29891;30072.84963575168;36632.81683591565;36744.63255367412;36945.891623251344;36981.63018369889;37160.44259336058;181975;182305;182408;182970;183886;28768.856155719088;29303.267418314714;29394;34347;34513.96458106261;35038.9488315351;35772.10569735777;35821.67159134752;39406.2118757629;39767;27228.591571126053;27546;28730.850765969128;29867.850660746557;30137.547936780527;23638;29079.56380654334;29239.561406579345;29583;29761.25596860096;33890.64437422574;34074.14814629555;34151;34551.82724086363;35391;28045.439091217653;28962;28995.13014609565;29586.485148762597;29996.550051748804;19247.1338789752;22482;23158.884205578863;23537.882310589022;23601.64597531004 11:10:13 INFO - PID 1647 | |2;jquery.html;483.63485568395856;485.654736380227;488.6367035969645;491.92129259318506;506.78968228185306;17825.37611183609;19427.32004379847;21557.9221038948;22266.88866555665;22373.328800136023;21828.563428731417;24329.270121896327;24530;24535.141270055552;24592.50814983704;6466.676666166696;6635.037919501669;6640.56836305641;6641.335866413368;6662.600243985376;6553.541252112354;6566.540342176049;6608.735650573972;6618.536702430808;6636.004599310094;3972.602739726033;5280.313559237294;5521.67479804846;6567.507436942225;6576.375244351802;6528.151340325751;6590.670466476657;6602.933970660304;6603.306652801459;6603.438707709803;6045.395460453963;6063.090536419572;6065.272167339904;6071.969640151771;6074.51403887688;28735.994240201493;29475.705242947726;29740.70259297401;29797;29817.552736708967;2191.430228140695;2267.9092836286527;2281.041962375806;2289.97710022901;2296.5406918616345;18684.81315186858;19194.904025479922;19268.132934018002;19276.614467710708;19309.51726206842;1962.6663467210608;2227.2093406840663;2242.192810588187;2288.8054515366216;2296;161786.19106904507;166065.16967415085;172628;172709;172839;13331.40008699611;14029.579112626638;14348.282585870666;15126.924365378103;15216.695666086838;20188.596228075356;20225.190992360436;20243;20256.797432025785;20280.89859550693;8195.46729462583;9453.574589143505;9552.522373881417;9742.610295588098;9837.55730992107;8788.736337909873;8911.598978046004;8947.686830960885;8969.327300452493;9020.909790902138;8177.855100285965;8633.784155396039;9012.81974360509;9090.590923408463;9180.44917304964;2567.2213194022142;2573.2923446052487;2589.5498520828396;2594.597837335216;2686.449277898026;966.3529916028662;970.8203982263304;989.6882482018141;997.4540508492234;1012.8885822559513;21228.575428491342;21229.46926326854;21596.784032159478;21757;22351;916.4455504419864;990.5740531571349;1012.2509343086064;1046.1212581431635;1046.602291129366;11131.332120072822;11161.386123763108;11319.094472442184;13826.654333641538;15287.082775033534;545591;551527;569486;578362;583767;134931;137164.62835371518;139614;142986.28506857398;143073;1216.154772433168;1218.8659247482947;1221.3221661977689;1245.769532636465;1253.974920501603;23181.768182318636;24258.272251832474;24314.878425608465;24421.14525991582;24489.755102448747;43232;67872;70850;71228.64385678039;71968;89045;89334.21331573202;90735;92574.53712731393;92910;10649.680509584725;11619.0704743622;11755.177137600285;11856.11079169083;11937.865902739226;13896.096753710977;18071.819281807013;18397.90801045986;18555.35056273024;18742.625147497245;14518.346674399467;14918.477853275082;15234.695306093594;15265.313060912507;15457.84542154564;11770.234934729215;12878.935605321913;13145.53990610324;13189.208647481182;13689.247091410069;15317.540473785804;15576.688466230838;15637.843621564094;15664.138472384127;15679.686406271583;11818.113641476752;11840.881591184323;11844.1709080367;11857.822132667845;11873.881261187278;14033;14947.701045979236;15117.848821512083;15140.318685659393;15175.24123793828 11:10:13 INFO - PID 1647 | |3;mootools.html;1395.0931894268726;1434.239852877975;1456.5557504960982;1463.3195564062714;1478.5786050975473;11438.828417573723;13170.473181072757;13768.862311376883;13771.931140344297;13798.689124533179;11546.595869144581;11590.130740194478;11681.649550513499;12635.936820315885;13150.210987340743;2702.6216329713807;2746.148693904886;2766.0457142285877;2768.6539182602173;2781.6244806951004;2712.3083613678486;2751.17464760572;2751.5047291487526;2753.6282601848793;2762.502749505088;2696.3798326384845;2722.7821774258014;2734.0567504211017;2739.205630367191;2740.6300149479766;1983.4049785064424;2344.15610380263;2347.460084180631;2678.7723043541323;2687.8924843006257;2333.918312859059;2351.3298709867604;2358.2217868103658;2571.2029270926073;2671.2787547362104;13195.736085278337;13311.667208319772;13314.800277995837;13315.267660278681;13317.600471985856;7314.45141614381;7573.129090154647;7608.657610407545;7611.961940190264;7618.657160427794;4537.160625284333;4842.12841688496;4915.705057696549;4918.114739346916;4939.678920870132;1363.277462944641;1387.1676993803737;1389.3192335755507;1412.3503188533286;1414.3988804757937;19753.703694444594;19891.7016244755;19895.900520497304;19954.301599444094;20082.497937551532;6747.392734653905;6849.794506164823;6906.136732908387;7042.802723536908;7079.433645308363;16366.099864507567;16369.345226191057;16487.67024659524;16528.83471165273;16530.83469165293;6833.487488438387;7670;7702.114256860421;7713.3443657288635;7721.150673425918;6472.482201423874;6720.596764194164;6860.862782744317;6862.210845752703;6883.208431030495;5201.557867581298;5367.865803354947;5378.435264297271;5400.648957817729;5459.754311056012;1650.4718490083299;1680.4790514940407;1690.0620155813397;1695.8134605193418;1698.1424380687674;664.7673314339943;706.8974998625171;854.6282367170177;856.0198572634299;867.926226270774;7260.346568808833;7437.888431673529;7600.543967361976;7661.348785353307;7896.763097106981;875.2123089219754;949.9192568631605;986.3243678080538;987.6345752071779;1027.660871912267;4742.739149346819;4897.7795999180125;4915.16442204826;4941.209406495015;5245.318108645928;17203.741943870853;17905.552361191076;17958.910205449152;17993.64012719738;18034;17448.389306374214;17561.824381756018;17708.937463752216;17734.29062837498;17746.822531774516;8876.20114189726;9449.338546301693;9474.194693450996;9486.810263794685;9518.238540916711;6523.380278873499;7670.2329767025085;7858.44990850635;8369.83260334802;8472.322214222724;15144.469943551923;15641.608959776097;15999.600009999844;16223.594410139842;16248.512544623207;11567.652970410902;15730.449434269745;16422;16568.751468727736;16621.584460388585;9794.75513112178;10514.474276286002;11610.477528511406;12333.075019373762;12340.81488777687;10466.842997354894;11025.889741102486;11887.940560297144;12113.636590902288;12317.384130793604;9824.607015719292;9915.454649994319;10130.544125514221;10184.490775461049;10436.947815260875;9538.523073846418;9636.180924621485;9993.900060999296;10073.84889226677;10218.029287217703;7202.855942881218;8856.760053592374;8898.665200220019;8910.198082172636;8965.148310910454;6530.6081635100145;6601.240857301472;6683.9331606685255;6700.363465470772;7011.684474198775;6764.898526522008;6830.7267709293055;7122.252163522756;7162.427005839416;7299.626644335096 11:10:13 INFO - PID 1647 | |4;prototype.html;421.8375925268771;439.1305215672966;446.83020452228146;466.42862493445557;466.9889688823697;6336.27132879719;6387.6806159692005;6417.454516366103;6423.807285781422;6436.613803171807;5049.9747501262445;5914.438128377808;6175.197224360838;6188.938110618892;6219.129321894939;4619.399478067847;4790.712557246577;4803.231482962727;4819.012102518984;4910.582600478963;4022.637962583367;4810.9037819243595;4863.8784030399165;4869.074875773592;4903.215485522317;4813.879653008667;4831.9275210871865;4834.323194752737;4839.225723884179;4850.830220942268;4821.37322148122;4835.758212089399;4852.514748525155;4865.683730557503;4887.902241955158;3773.3585290500682;4264.48826140862;4333.241682705546;4340.218760623086;4361.236783562896;5827.125931110325;5992.520598352165;6051.636901785863;6122.418370254819;6131.570790044699;5265.947340526584;5654.6324488908085;5683.318001839806;5684.943150568482;5688.63023903445;5632.831015069554;5961.433663801975;6854.554453960526;6871.244163142101;6875.48433867457;5053.974730126362;5062.468440813698;5075.822346217901;5077.959018401219;5094.337736094321;6496.837579060514;7113.221694576304;7120.92879071213;7121.145462544476;7135.464840136959;4351.369051487517;4374.518802931675;4917.721392437992;4980.003999200175;5252.343457067866;4823.710577365369;5207.895842083137;5319.494648008433;5350.892982140335;5350.9732451337495;4878.438819619657;5835.299764028259;5852.590318677651;5854.648721076749;5898.616589921641;6484.605809750832;6824.587706146926;6844.760433384808;6851.314868513109;6884;1615.7980252468442;1816.9182386792627;1837.8713490055836;1841.8618603604516;1842.9631407371778;1166.8658104318113;1195.3485350484007;1307.7972914198315;1308.4494873574845;1326.8540460549332;6007.519398448114;6312.179416675803;6325.367463253638;6365.172527571479;6379.808605741835;6941.34057264559;6941.375276225164;6971;6985.965070174719;7003.614801185881;5642.463965923231;6223.1287619733275;6274.749010039639;6343.175387199727;6351.936480635227;2922.824630522133;3407.880724174642;3475.5308033415167;3629.909252268714;3639.94540081899;6808;6926.480513961473;7032.367086962198;7119.540494198677;7123.2520585338825;6133.233345831771;6737.427318677967;6954.756583519553;7017.631561845391;7049.295070493013;5270.683758974397;6586.604803711793;6591.307912669196;7135.89296160558;7150.570965742072;6963.442924566124;6968.965155174192;6991.580505169706;6994.440444764305;7036;6535.967320163369;6693.330666933268;6880.105586273752;6900.827479313058;6942.791716248521;5904.557158213237;5952.791652292149;6849;7171;7179.210286868336;4070.5929407060244;4557.794899229476;5172.275881376535;5187.740612969412;5195.506426889439;6754.5947243165565;7006.929930700628;7009.02116600453;7012.6844292008;7016;6034.306054803754;6116.847078823065;6754.527183097136;6791.864162716619;6793.626350550767;6715.3620406061345;6747.4264687500145;6748.223954245324;6753.257141714309;6777.864442711019;6175.783847565313;6574.9151390020115;6601.953394447736;6602.151397608677;6650;5662.631928924607;6054.9101161791305;6185.41238582334;6284.277308109626;6636.170478690164;5628.577856660685;6056.879477296714;6294.496440284858;6551.574147680385;6623.933760662524 11:10:13 INFO - PID 1647 | |5;yui.html;755.5919803306215;813.53221897409;823.1891586787018;855.5422848775906;856.3063918226237;16619.66760664786;17004.149792510372;17092.572685682833;17223.966562006273;17405.47783566492;10947.288426252308;12835.165714228591;13531.120477168977;13859.584212473643;13981.650458738537;661.1999480628446;875.5534677314583;879.3580686099119;970.0590427285522;970.1123472023125;1008.1682611845221;1012.8025035118155;1016.5527168046073;1016.959321627134;1017.2675673515057;1061.867266591676;1064.675274041417;1067.588978243378;1068.4978060311641;1071.3518321415547;682.1098466501214;1036.974075648111;1063.500154927183;1063.973400664982;1064.2816099133092;514.0259208799305;517.5057819782099;518.0674785386327;518.2356024863334;741.2476336518438;17297.481075567754;17544.736828947574;17546.736798948026;17565.121743912754;17569.912150439166;17002;17305.307787688478;17917;17957.64084718298;18147.18337674808;14654.194019328932;14792.482263120846;14820.036697614729;14969.850301497063;15077.095374277578;3624.691901188402;3627.8934924847913;3638.0177352115047;3659.2864391443677;3662.3224703429682;101266.49366753119;117645.82354176434;147149.52850471466;152576.2371188137;154838;11067.114630829676;11627.76744465106;12598.307093109783;14164.504242351468;14167.929160354131;25977.350566235997;26406.471870562866;26623.201303960912;26926.461470770868;27029.86485067562;7807.570583617956;8086.474379165335;8106;8107.675692972215;8128.024637043474;3882.6699730523223;3963.564007959122;3969.603039696065;3975.7018223633345;3980.8208630611693;4534.7052441591195;4593.655475839326;4600.42494688164;4623.329617205553;4635.559621835921;912.2884150362728;924.3621900888365;943.9339246252833;1006.4011912911806;1032.4682788363998;642.6568471893764;645.6578013652771;659.1167835100988;675.2841987493273;689.4449967775988;9698.466584337788;10827.296225745458;11063;11928.687844337115;12344.506219751109;603.3182503770711;620.419907386595;621.3724138620026;647.9546431749826;664.9567778094408;5038.244263360525;5208.635395522353;5287.656302340336;5328.600354973392;5446.591505637014;139303;141596.29201853924;142151;145351;147233;90136.09863901408;119547;121892;122273;126076;76459.61770191003;101647;102365;102567;110719;9852.704418867446;10636.468176591294;10933.61732339396;12159.209651372632;12562.309073000708;39010.80494597509;44296;44930;47726.76136619295;48574.75712621414;82733;86762;88386.55806721182;89840;92819.53590232276;16713.832861671228;16929.746053808958;17138.828611713725;17217.139143042546;17276.740848887024;18855.90572047131;19228.519287017938;19580.902095489433;19607.705884411458;19613.215471380983;17396.82603173952;17712.645747084727;17842.91078544599;17859.196336164645;18087.457376278733;14211;17431.302747889942;17493.650126997643;18241.179146938684;18435.26258949627;16449.753253700965;17141.57146071358;17539.070702223;17619.471415857544;18050.368237111637;11606.825897611374;11805.763884722086;12060.457279422271;12437.253764774143;12732.108752387614;12947.741045178855;13187.340632968506;13259.403326850508;13621.182729036289;13997.93001035029 11:10:13 INFO - PID 1647 | __end_tp_report 11:10:13 INFO - PID 1647 | __start_cc_report 11:10:13 INFO - PID 1647 | _x_x_mozilla_cycle_collect,480 11:10:13 INFO - PID 1647 | __end_cc_report 11:10:13 INFO - PID 1647 | __startTimestamp1496945413914__endTimestamp 11:10:13 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | ------- Summary: start ------- 11:10:13 INFO - PID 1647 | Number of tests: 6 11:10:13 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6175.73 Median:6416.97 stddev:791.27 (12.3%) stddev-sans-first:752.10 11:10:13 INFO - PID 1647 | Values: 2948.4 3529.1 3535.2 3634.7 3657.3 5091.5 6188.4 6217.0 6290.7 6324.5 6276.2 6281.7 6292.2 6299.0 6305.9 5426.4 5450.2 5454.6 5467.9 5583.2 5504.1 5523.2 5528.6 5538.1 5545.8 5505.3 5522.9 5561.2 5563.2 5564.0 5512.0 5522.7 5529.8 5556.8 5566.9 5404.5 5444.6 5501.2 5521.6 5529.7 6318.0 6330.7 6356.1 6406.2 6428.5 6616.2 6724.3 6726.7 6850.5 6862.0 6153.9 6774.6 6795.6 6796.6 6797.4 5704.5 6345.1 6345.7 6361.6 6365.5 5399.3 6985.5 7069.6 7081.4 7082.9 6499.8 6659.0 6679.8 6703.4 6704.2 6690.8 6892.2 6907.5 6910.2 6915.1 6650.5 6657.1 6674.7 6678.8 6686.8 4947.6 6827.2 6840.8 6848.1 6859.2 5330.7 5358.7 5373.4 5410.7 5452.2 4760.5 4791.3 4797.0 4819.0 4827.6 6231.9 6313.0 6341.5 6346.5 6417.9 6820.4 6827.6 6832.5 6836.9 6842.1 5345.3 6193.1 6353.1 6417.0 6434.9 4921.9 5897.4 5948.8 5968.7 6059.0 6904.4 6916.6 6923.2 6925.1 6932.7 6774.0 6774.9 6785.4 6785.9 6793.9 5067.9 5940.3 6920.0 6926.2 7068.9 5617.7 6031.2 6569.0 6794.5 6832.4 6957.9 6962.9 6970.7 6997.3 7003.9 6503.1 6611.9 6646.0 6733.4 6765.9 5205.8 5866.3 6583.2 6613.6 6622.7 4825.6 6534.5 6651.7 6659.5 6663.3 6693.9 6714.7 6751.8 6757.1 6801.8 6339.1 6584.3 6589.3 6596.2 6622.2 11:10:13 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:40378.80 Median:324897.68 stddev:69568.24 (21.4%) stddev-sans-first:69699.79 11:10:13 INFO - PID 1647 | Values: 757.8 779.0 823.5 837.9 1092.8 25567.2 26342.0 26351.0 36470.6 36870.3 38401.4 38785.4 38849.8 38906.0 39044.4 6735.6 8233.6 8237.5 8258.3 8279.2 7995.9 8070.2 8092.9 8101.2 8104.5 7367.2 7572.0 8079.0 8124.4 8151.8 8112.4 8154.8 8171.5 8184.3 8187.1 7900.3 7941.4 7984.7 7987.3 7994.0 28230.6 34733.7 34740.0 34813.8 34870.3 3009.4 3512.9 3540.6 3545.4 3549.6 28883.1 29011.3 29013.1 29088.9 29264.9 2994.2 3053.5 3054.1 3054.8 3064.3 245817.0 251724.0 252092.0 292172.0 295205.0 37557.2 37625.4 43064.0 44319.9 46172.3 48461.5 48466.5 48666.8 48725.5 48839.8 13447.4 13515.1 13556.3 13559.1 13571.5 11260.9 11270.3 11294.0 11317.8 11318.9 13098.9 13130.5 13143.1 13145.3 13227.6 5799.8 5861.1 5872.2 5878.1 5891.9 951.7 953.5 954.2 990.9 1014.5 19163.7 22198.1 23058.4 23252.7 23833.0 993.0 1013.9 1094.3 1094.7 1095.5 10065.9 11279.8 11519.0 11850.8 12131.4 316684.0 322969.0 326826.4 327352.0 327374.4 51595.2 51755.7 51786.7 51868.7 51964.2 1330.5 1455.9 1468.1 1468.4 1472.2 27407.2 27711.0 29596.9 29891.0 30072.8 36632.8 36744.6 36945.9 36981.6 37160.4 181975.0 182305.0 182408.0 182970.0 183886.0 28768.9 29303.3 29394.0 34347.0 34514.0 35038.9 35772.1 35821.7 39406.2 39767.0 27228.6 27546.0 28730.9 29867.9 30137.5 23638.0 29079.6 29239.6 29583.0 29761.3 33890.6 34074.1 34151.0 34551.8 35391.0 28045.4 28962.0 28995.1 29586.5 29996.6 19247.1 22482.0 23158.9 23537.9 23601.6 11:10:13 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:38485.28 Median:2284.92 stddev:96547.76 (4225.4%) stddev-sans-first:96776.44 11:10:13 INFO - PID 1647 | Values: 483.6 485.7 488.6 491.9 506.8 17825.4 19427.3 21557.9 22266.9 22373.3 21828.6 24329.3 24530.0 24535.1 24592.5 6466.7 6635.0 6640.6 6641.3 6662.6 6553.5 6566.5 6608.7 6618.5 6636.0 3972.6 5280.3 5521.7 6567.5 6576.4 6528.2 6590.7 6602.9 6603.3 6603.4 6045.4 6063.1 6065.3 6072.0 6074.5 28736.0 29475.7 29740.7 29797.0 29817.6 2191.4 2267.9 2281.0 2290.0 2296.5 18684.8 19194.9 19268.1 19276.6 19309.5 1962.7 2227.2 2242.2 2288.8 2296.0 161786.2 166065.2 172628.0 172709.0 172839.0 13331.4 14029.6 14348.3 15126.9 15216.7 20188.6 20225.2 20243.0 20256.8 20280.9 8195.5 9453.6 9552.5 9742.6 9837.6 8788.7 8911.6 8947.7 8969.3 9020.9 8177.9 8633.8 9012.8 9090.6 9180.4 2567.2 2573.3 2589.5 2594.6 2686.4 966.4 970.8 989.7 997.5 1012.9 21228.6 21229.5 21596.8 21757.0 22351.0 916.4 990.6 1012.3 1046.1 1046.6 11131.3 11161.4 11319.1 13826.7 15287.1 545591.0 551527.0 569486.0 578362.0 583767.0 134931.0 137164.6 139614.0 142986.3 143073.0 1216.2 1218.9 1221.3 1245.8 1254.0 23181.8 24258.3 24314.9 24421.1 24489.8 43232.0 67872.0 70850.0 71228.6 71968.0 89045.0 89334.2 90735.0 92574.5 92910.0 10649.7 11619.1 11755.2 11856.1 11937.9 13896.1 18071.8 18397.9 18555.4 18742.6 14518.3 14918.5 15234.7 15265.3 15457.8 11770.2 12878.9 13145.5 13189.2 13689.2 15317.5 15576.7 15637.8 15664.1 15679.7 11818.1 11840.9 11844.2 11857.8 11873.9 14033.0 14947.7 15117.8 15140.3 15175.2 11:10:13 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:8189.59 Median:2739.92 stddev:5382.14 (196.4%) stddev-sans-first:5373.02 11:10:13 INFO - PID 1647 | Values: 1395.1 1434.2 1456.6 1463.3 1478.6 11438.8 13170.5 13768.9 13771.9 13798.7 11546.6 11590.1 11681.6 12635.9 13150.2 2702.6 2746.1 2766.0 2768.7 2781.6 2712.3 2751.2 2751.5 2753.6 2762.5 2696.4 2722.8 2734.1 2739.2 2740.6 1983.4 2344.2 2347.5 2678.8 2687.9 2333.9 2351.3 2358.2 2571.2 2671.3 13195.7 13311.7 13314.8 13315.3 13317.6 7314.5 7573.1 7608.7 7612.0 7618.7 4537.2 4842.1 4915.7 4918.1 4939.7 1363.3 1387.2 1389.3 1412.4 1414.4 19753.7 19891.7 19895.9 19954.3 20082.5 6747.4 6849.8 6906.1 7042.8 7079.4 16366.1 16369.3 16487.7 16528.8 16530.8 6833.5 7670.0 7702.1 7713.3 7721.2 6472.5 6720.6 6860.9 6862.2 6883.2 5201.6 5367.9 5378.4 5400.6 5459.8 1650.5 1680.5 1690.1 1695.8 1698.1 664.8 706.9 854.6 856.0 867.9 7260.3 7437.9 7600.5 7661.3 7896.8 875.2 949.9 986.3 987.6 1027.7 4742.7 4897.8 4915.2 4941.2 5245.3 17203.7 17905.6 17958.9 17993.6 18034.0 17448.4 17561.8 17708.9 17734.3 17746.8 8876.2 9449.3 9474.2 9486.8 9518.2 6523.4 7670.2 7858.4 8369.8 8472.3 15144.5 15641.6 15999.6 16223.6 16248.5 11567.7 15730.4 16422.0 16568.8 16621.6 9794.8 10514.5 11610.5 12333.1 12340.8 10466.8 11025.9 11887.9 12113.6 12317.4 9824.6 9915.5 10130.5 10184.5 10436.9 9538.5 9636.2 9993.9 10073.8 10218.0 7202.9 8856.8 8898.7 8910.2 8965.1 6530.6 6601.2 6683.9 6700.4 7011.7 6764.9 6830.7 7122.3 7162.4 7299.6 11:10:13 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5535.02 Median:6055.89 stddev:1631.90 (26.9%) stddev-sans-first:1590.71 11:10:13 INFO - PID 1647 | Values: 421.8 439.1 446.8 466.4 467.0 6336.3 6387.7 6417.5 6423.8 6436.6 5050.0 5914.4 6175.2 6188.9 6219.1 4619.4 4790.7 4803.2 4819.0 4910.6 4022.6 4810.9 4863.9 4869.1 4903.2 4813.9 4831.9 4834.3 4839.2 4850.8 4821.4 4835.8 4852.5 4865.7 4887.9 3773.4 4264.5 4333.2 4340.2 4361.2 5827.1 5992.5 6051.6 6122.4 6131.6 5265.9 5654.6 5683.3 5684.9 5688.6 5632.8 5961.4 6854.6 6871.2 6875.5 5054.0 5062.5 5075.8 5078.0 5094.3 6496.8 7113.2 7120.9 7121.1 7135.5 4351.4 4374.5 4917.7 4980.0 5252.3 4823.7 5207.9 5319.5 5350.9 5351.0 4878.4 5835.3 5852.6 5854.6 5898.6 6484.6 6824.6 6844.8 6851.3 6884.0 1615.8 1816.9 1837.9 1841.9 1843.0 1166.9 1195.3 1307.8 1308.4 1326.9 6007.5 6312.2 6325.4 6365.2 6379.8 6941.3 6941.4 6971.0 6986.0 7003.6 5642.5 6223.1 6274.7 6343.2 6351.9 2922.8 3407.9 3475.5 3629.9 3639.9 6808.0 6926.5 7032.4 7119.5 7123.3 6133.2 6737.4 6954.8 7017.6 7049.3 5270.7 6586.6 6591.3 7135.9 7150.6 6963.4 6969.0 6991.6 6994.4 7036.0 6536.0 6693.3 6880.1 6900.8 6942.8 5904.6 5952.8 6849.0 7171.0 7179.2 4070.6 4557.8 5172.3 5187.7 5195.5 6754.6 7006.9 7009.0 7012.7 7016.0 6034.3 6116.8 6754.5 6791.9 6793.6 6715.4 6747.4 6748.2 6753.3 6777.9 6175.8 6574.9 6602.0 6602.2 6650.0 5662.6 6054.9 6185.4 6284.3 6636.2 5628.6 6056.9 6294.5 6551.6 6623.9 11:10:13 INFO - PID 1647 | 11:10:13 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:25410.32 Median:17594.69 stddev:38710.21 (220.0%) stddev-sans-first:38774.54 11:10:13 INFO - PID 1647 | Values: 755.6 813.5 823.2 855.5 856.3 16619.7 17004.1 17092.6 17224.0 17405.5 10947.3 12835.2 13531.1 13859.6 13981.7 661.2 875.6 879.4 970.1 970.1 1008.2 1012.8 1016.6 1017.0 1017.3 1061.9 1064.7 1067.6 1068.5 1071.4 682.1 1037.0 1063.5 1064.0 1064.3 514.0 517.5 518.1 518.2 741.2 17297.5 17544.7 17546.7 17565.1 17569.9 17002.0 17305.3 17917.0 17957.6 18147.2 14654.2 14792.5 14820.0 14969.9 15077.1 3624.7 3627.9 3638.0 3659.3 3662.3 101266.5 117645.8 147149.5 152576.2 154838.0 11067.1 11627.8 12598.3 14164.5 14167.9 25977.4 26406.5 26623.2 26926.5 27029.9 7807.6 8086.5 8106.0 8107.7 8128.0 3882.7 3963.6 3969.6 3975.7 3980.8 4534.7 4593.7 4600.4 4623.3 4635.6 912.3 924.4 943.9 1006.4 1032.5 642.7 645.7 659.1 675.3 689.4 9698.5 10827.3 11063.0 11928.7 12344.5 603.3 620.4 621.4 648.0 665.0 5038.2 5208.6 5287.7 5328.6 5446.6 139303.0 141596.3 142151.0 145351.0 147233.0 90136.1 119547.0 121892.0 122273.0 126076.0 76459.6 101647.0 102365.0 102567.0 110719.0 9852.7 10636.5 10933.6 12159.2 12562.3 39010.8 44296.0 44930.0 47726.8 48574.8 82733.0 86762.0 88386.6 89840.0 92819.5 16713.8 16929.7 17138.8 17217.1 17276.7 18855.9 19228.5 19580.9 19607.7 19613.2 17396.8 17712.6 17842.9 17859.2 18087.5 14211.0 17431.3 17493.7 18241.2 18435.3 16449.8 17141.6 17539.1 17619.5 18050.4 11606.8 11805.8 12060.5 12437.3 12732.1 12947.7 13187.3 13259.4 13621.2 13997.9 11:10:13 INFO - PID 1647 | -------- Summary: end -------- 11:10:13 INFO - PID 1647 | 11:10:14 INFO - TEST-INFO | 1647: exit 0 11:10:14 INFO - TEST-OK | dromaeo_css | took 1144980ms 11:10:14 INFO - TEST-START | kraken 11:10:14 INFO - operating with platform_type : mac_ 11:10:14 INFO - Initialising browser for kraken test... 11:10:14 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpvjaami/profile 11:10:14 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 11:10:48 INFO - TEST-INFO | 1658: killed by SIGTERM 11:10:48 INFO - Browser initialized. 11:10:48 INFO - Running cycle 1/1 for kraken test... 11:10:48 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpvjaami/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:10:50 INFO - PID 1662 | 2017-06-08 11:10:50.727 plugin-container[1663:10618] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 11:10:50 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:10:50 INFO - PID 1662 | 2017-06-08 11:10:50.728 plugin-container[1663:10618] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 11:10:50 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:10:50 INFO - PID 1662 | 2017-06-08 11:10:50.728 plugin-container[1663:10618] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:10:51 INFO - PID 1662 | 2017-06-08 11:10:51.200 plugin-container[1664:10664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 11:10:51 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:10:51 INFO - PID 1662 | 2017-06-08 11:10:51.201 plugin-container[1664:10664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d03, name = 'com.apple.CFPasteboardClient' 11:10:51 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:10:51 INFO - PID 1662 | 2017-06-08 11:10:51.201 plugin-container[1664:10664] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:11:00 INFO - PID 1662 | RSS: Main: 253157376 11:11:00 INFO - PID 1662 | 11:11:03 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 11:11:04 INFO - PID 1662 | RSS: Main: 264773632 11:11:04 INFO - PID 1662 | 11:11:06 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 11:11:07 INFO - PID 1662 | RSS: Main: 274505728 11:11:07 INFO - PID 1662 | 11:11:11 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 11:11:11 INFO - PID 1662 | RSS: Main: 277291008 11:11:11 INFO - PID 1662 | 11:11:13 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 11:11:14 INFO - PID 1662 | RSS: Main: 278392832 11:11:14 INFO - PID 1662 | 11:11:16 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:11:17 INFO - PID 1662 | RSS: Main: 280154112 11:11:17 INFO - PID 1662 | 11:11:19 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 11:11:20 INFO - PID 1662 | RSS: Main: 285442048 11:11:20 INFO - PID 1662 | 11:11:23 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 11:11:24 INFO - PID 1662 | RSS: Main: 290828288 11:11:24 INFO - PID 1662 | 11:11:27 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 11:11:28 INFO - PID 1662 | RSS: Main: 289558528 11:11:28 INFO - PID 1662 | 11:11:29 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:11:30 INFO - PID 1662 | RSS: Main: 289787904 11:11:30 INFO - PID 1662 | 11:11:32 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:11:32 INFO - PID 1662 | RSS: Main: 289517568 11:11:32 INFO - PID 1662 | 11:11:35 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:11:35 INFO - PID 1662 | RSS: Main: 289550336 11:11:35 INFO - PID 1662 | 11:11:38 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:11:39 INFO - PID 1662 | RSS: Main: 289656832 11:11:39 INFO - PID 1662 | 11:11:42 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:11:43 INFO - PID 1662 | RSS: Main: 289439744 11:11:43 INFO - PID 1662 | 11:11:44 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 11:11:45 INFO - PID 1662 | RSS: Main: 291237888 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | __start_tp_report 11:11:45 INFO - PID 1662 | _x_x_mozilla_page_load 11:11:45 INFO - PID 1662 | _x_x_mozilla_page_load_details 11:11:45 INFO - PID 1662 | |i|pagename|runs| 11:11:45 INFO - PID 1662 | |0;ai-astar;105;115;121;119;121;168;167;114;116;139 11:11:45 INFO - PID 1662 | |1;audio-beat-detection;156;115;148;135;116;149;132;105;157;113 11:11:45 INFO - PID 1662 | |2;audio-dft;209;204;211;233;259;285;288;200;201;200 11:11:45 INFO - PID 1662 | |3;audio-fft;72;85;72;128;82;85;86;89;95;58 11:11:45 INFO - PID 1662 | |4;audio-oscillator;70;70;107;72;71;75;107;69;72;107 11:11:45 INFO - PID 1662 | |5;imaging-gaussian-blur;89;90;92;90;91;88;89;90;89;89 11:11:45 INFO - PID 1662 | |6;imaging-darkroom;100;152;98;99;97;98;120;153;128;99 11:11:45 INFO - PID 1662 | |7;imaging-desaturate;93;96;95;92;91;90;90;93;116;147 11:11:45 INFO - PID 1662 | |8;json-parse-financial;50;48;49;54;53;53;54;53;54;52 11:11:45 INFO - PID 1662 | |9;json-stringify-tinderbox;57;57;57;63;62;71;66;59;60;59 11:11:45 INFO - PID 1662 | |10;stanford-crypto-aes;105;83;83;83;82;96;82;92;126;100 11:11:45 INFO - PID 1662 | |11;stanford-crypto-ccm;159;152;147;144;144;146;145;137;150;130 11:11:45 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;182;185;217;189;178;184;211;233;189;181 11:11:45 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;67;75;92;97;73;62;65;63;82;95 11:11:45 INFO - PID 1662 | __end_tp_report 11:11:45 INFO - PID 1662 | __start_cc_report 11:11:45 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1197 11:11:45 INFO - PID 1662 | __end_cc_report 11:11:45 INFO - PID 1662 | __startTimestamp1496945505063__endTimestamp 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | ------- Summary: start ------- 11:11:45 INFO - PID 1662 | Number of tests: 14 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:128.50 Median:121.00 stddev:22.25 (18.4%) stddev-sans-first:21.92 11:11:45 INFO - PID 1662 | Values: 105.0 115.0 121.0 119.0 121.0 168.0 167.0 114.0 116.0 139.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:141.50 stddev:19.40 (13.7%) stddev-sans-first:18.63 11:11:45 INFO - PID 1662 | Values: 156.0 115.0 148.0 135.0 116.0 149.0 132.0 105.0 157.0 113.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:229.00 Median:222.00 stddev:35.51 (16.0%) stddev-sans-first:36.92 11:11:45 INFO - PID 1662 | Values: 209.0 204.0 211.0 233.0 259.0 285.0 288.0 200.0 201.0 200.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:85.20 Median:85.00 stddev:18.38 (21.6%) stddev-sans-first:18.87 11:11:45 INFO - PID 1662 | Values: 72.0 85.0 72.0 128.0 82.0 85.0 86.0 89.0 95.0 58.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:70.50 stddev:17.33 (24.6%) stddev-sans-first:17.83 11:11:45 INFO - PID 1662 | Values: 70.0 70.0 107.0 72.0 71.0 75.0 107.0 69.0 72.0 107.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:90.00 stddev:1.16 (1.3%) stddev-sans-first:1.20 11:11:45 INFO - PID 1662 | Values: 89.0 90.0 92.0 90.0 91.0 88.0 89.0 90.0 89.0 89.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:114.40 Median:97.50 stddev:22.71 (23.3%) stddev-sans-first:23.48 11:11:45 INFO - PID 1662 | Values: 100.0 152.0 98.0 99.0 97.0 98.0 120.0 153.0 128.0 99.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:100.30 Median:92.50 stddev:18.10 (19.6%) stddev-sans-first:19.00 11:11:45 INFO - PID 1662 | Values: 93.0 96.0 95.0 92.0 91.0 90.0 90.0 93.0 116.0 147.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:53.00 stddev:2.21 (4.2%) stddev-sans-first:2.22 11:11:45 INFO - PID 1662 | Values: 50.0 48.0 49.0 54.0 53.0 53.0 54.0 53.0 54.0 52.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:4.56 (7.5%) stddev-sans-first:4.59 11:11:45 INFO - PID 1662 | Values: 57.0 57.0 57.0 63.0 62.0 71.0 66.0 59.0 60.0 59.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:83.00 stddev:14.27 (17.2%) stddev-sans-first:14.49 11:11:45 INFO - PID 1662 | Values: 105.0 83.0 83.0 83.0 82.0 96.0 82.0 92.0 126.0 100.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:146.50 stddev:7.92 (5.4%) stddev-sans-first:6.70 11:11:45 INFO - PID 1662 | Values: 159.0 152.0 147.0 144.0 144.0 146.0 145.0 137.0 150.0 130.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.90 Median:189.00 stddev:18.65 (9.9%) stddev-sans-first:19.19 11:11:45 INFO - PID 1662 | Values: 182.0 185.0 217.0 189.0 178.0 184.0 211.0 233.0 189.0 181.0 11:11:45 INFO - PID 1662 | 11:11:45 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.10 Median:78.50 stddev:13.58 (17.3%) stddev-sans-first:13.90 11:11:45 INFO - PID 1662 | Values: 67.0 75.0 92.0 97.0 73.0 62.0 65.0 63.0 82.0 95.0 11:11:45 INFO - PID 1662 | -------- Summary: end -------- 11:11:45 INFO - PID 1662 | 11:11:45 INFO - TEST-INFO | 1662: exit 0 11:11:45 INFO - TEST-OK | kraken | took 91306ms 11:11:45 INFO - SUITE-END | took 1236s 11:11:46 INFO - Completed test suite (00:20:36) 11:11:46 INFO - javascript benchmark 11:11:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8233.105899482132, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2948.3661012882226, 3529.1177205698573, 3535.186907011389, 3634.745567810253, 3657.2685462907425, 5091.490850914916, 6188.443040126388, 6217.0, 6290.685465726707, 6324.525660575458, 6276.215473065867, 6281.717322720478, 6292.182016337881, 6298.968505157479, 6305.896468118084, 5426.375966763833, 5450.182472629108, 5454.59090568207, 5467.933752918182, 5583.246261754672, 5504.091824848895, 5523.171524271352, 5528.612997090185, 5538.113901775717, 5545.833624991256, 5505.3393592768925, 5522.889542209154, 5561.221428999943, 5563.221149039137, 5564.0, 5511.9724401377935, 5522.66863988162, 5529.806456773994, 5556.805511807108, 5566.888662226774, 5404.4595540446035, 5444.646098003617, 5501.229827824108, 5521.641093328921, 5529.695866727328, 6317.968410157966, 6330.68346582669, 6356.110144579762, 6406.23125224976, 6428.517861160385, 6616.172978377703, 6724.260331363546, 6726.663666816639, 6850.451963842881, 6862.0, 6153.938460615426, 6774.593524388503, 6795.558288711218, 6796.558223715443, 6797.354251346114, 5704.458076482728, 6345.07996340528, 6345.714442850082, 6361.632249066427, 6365.522585806083, 5399.305483526955, 6985.545939513915, 7069.646517674096, 7081.398081163055, 7082.929170708227, 6499.837504062341, 6659.001149827468, 6679.833004174934, 6703.363180497845, 6704.161979752531, 6690.765823196165, 6892.207396149507, 6907.481938854606, 6910.170779506491, 6915.135608048994, 6650.501212409089, 6657.1345725055435, 6674.733010679615, 6678.766243181465, 6686.76294885438, 4947.5794557462295, 6827.21487028998, 6840.760573379908, 6848.075509806113, 6859.17689877208, 5330.653507521999, 5358.678479291255, 5373.408925018245, 5410.702411367412, 5452.154915988028, 4760.523947605212, 4791.257355109963, 4796.9760151199025, 4819.012102518976, 4827.637927155477, 6231.875362492816, 6313.0, 6341.460975817107, 6346.460550853137, 6417.8716425672155, 6820.386165245052, 6827.57621044532, 6832.521723479309, 6836.897446538306, 6842.110525631636, 5345.251664766935, 6193.102000209944, 6353.110564520971, 6416.967915160394, 6434.871302573923, 4921.87695307613, 5897.3807750185615, 5948.791792287336, 5968.7015649217365, 6058.969705151446, 6904.378605925491, 6916.585004899722, 6923.169219693669, 6925.134358205224, 6932.6533673316135, 6773.950037744157, 6774.898376524257, 6785.423239024738, 6785.93214067853, 6793.898091528532, 5067.898642027065, 5940.346561878104, 6919.965400172967, 6926.168859736864, 7068.893966590403, 5617.662940223599, 6031.24609423822, 6568.967155164193, 6794.4904132189295, 6832.350926661959, 6957.895631565631, 6962.895556566555, 6970.745265852195, 6997.335253150943, 7003.859922801414, 6503.089567460464, 6611.900821487586, 6646.003099535108, 6733.42765864907, 6765.932340676531, 5205.843824685266, 5866.266716660418, 6583.177102862142, 6613.603183808987, 6622.668866556556, 4825.5898248649255, 6534.542582019213, 6651.700673469609, 6659.467242620675, 6663.333666633298, 6693.899591506034, 6714.664266786739, 6751.797446076625, 6757.087793147862, 6801.761938332134, 6339.080833279057, 6584.308647591934, 6589.341065893564, 6596.241432235162, 6622.1722284714415], "value": 6125.405876194753, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.7575175943697, 778.9883151752724, 823.5017814222397, 837.932965362771, 1092.7869065532213, 25567.232983010515, 26342.0, 26351.0, 36470.63529364706, 36870.26259474816, 38401.42397864034, 38785.41821872667, 38849.80575097121, 38906.02734931628, 39044.41433378502, 6735.595864248148, 8233.588320583976, 8237.546934918577, 8258.256756891878, 8279.213474719892, 7995.920040799605, 8070.152633973436, 8092.919070809276, 8101.230383113595, 8104.5137291762385, 7367.1527774306105, 7571.962140189319, 8078.959605201966, 8124.350051995856, 8151.755447336589, 8112.351011919032, 8154.796130096735, 8171.468854524496, 8184.263416292562, 8187.1403502631965, 7900.2889739923685, 7941.404394670423, 7984.680612775482, 7987.281144697005, 7994.040715114165, 28230.576541351897, 34733.652663473295, 34740.0, 34813.78151764676, 34870.30259394798, 3009.368032713133, 3512.877049303262, 3540.6105328413855, 3545.4150065239205, 3549.6272891346553, 28883.133505994854, 29011.274718132005, 29013.129606111852, 29088.854555727085, 29264.853675731698, 2994.2364697002345, 3053.4656435123766, 3054.144839444959, 3054.7861649684532, 3064.264576501646, 245817.0, 251724.0, 252092.0, 292172.0, 295205.0, 37557.248855023296, 37625.435618465744, 43064.0, 44319.89200269955, 46172.307415388794, 48461.5153848457, 48466.5153348469, 48666.75666621644, 48725.53823385304, 48839.75580122077, 13447.394867230998, 13515.121517101357, 13556.254406007567, 13559.101725758403, 13571.52499662507, 11260.887391126147, 11270.323780573191, 11293.983541481306, 11317.83023254652, 11318.94340528292, 13098.934505327412, 13130.540431084866, 13143.07998440119, 13145.342732863319, 13227.603171904859, 5799.811038737049, 5861.120831875168, 5872.236609240771, 5878.147668588031, 5891.941080589225, 951.6716732727198, 953.5280036382036, 954.1555723185031, 990.8761404824253, 1014.4927536231884, 19163.65854390207, 22198.12002892267, 23058.423539411313, 23252.65121023152, 23833.0, 992.9503524823874, 1013.9188864890939, 1094.2668412163869, 1094.7208461842338, 1095.4851219926622, 10065.899341006496, 11279.830802537968, 11519.0, 11850.81491850808, 12131.393430328448, 316684.0, 322969.0, 326826.3658681691, 327352.0, 327374.3631281876, 51595.22607160896, 51755.741221294404, 51786.741066294424, 51868.740656296475, 51964.22053669198, 1330.5476138113088, 1455.9126452412888, 1468.067776961637, 1468.383279022813, 1472.212366384001, 27407.17778466649, 27711.0, 29596.852015739783, 29891.0, 30072.84963575168, 36632.81683591565, 36744.63255367412, 36945.891623251344, 36981.63018369889, 37160.44259336058, 181975.0, 182305.0, 182408.0, 182970.0, 183886.0, 28768.856155719088, 29303.267418314714, 29394.0, 34347.0, 34513.96458106261, 35038.9488315351, 35772.10569735777, 35821.67159134752, 39406.2118757629, 39767.0, 27228.591571126053, 27546.0, 28730.850765969128, 29867.850660746557, 30137.547936780527, 23638.0, 29079.56380654334, 29239.561406579345, 29583.0, 29761.25596860096, 33890.64437422574, 34074.14814629555, 34151.0, 34551.82724086363, 35391.0, 28045.439091217653, 28962.0, 28995.13014609565, 29586.485148762597, 29996.550051748804, 19247.1338789752, 22482.0, 23158.884205578863, 23537.882310589022, 23601.64597531004], "value": 16415.778416638826, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.63485568395856, 485.654736380227, 488.6367035969645, 491.92129259318506, 506.78968228185306, 17825.37611183609, 19427.32004379847, 21557.9221038948, 22266.88866555665, 22373.328800136023, 21828.563428731417, 24329.270121896327, 24530.0, 24535.141270055552, 24592.50814983704, 6466.676666166696, 6635.037919501669, 6640.56836305641, 6641.335866413368, 6662.600243985376, 6553.541252112354, 6566.540342176049, 6608.735650573972, 6618.536702430808, 6636.004599310094, 3972.602739726033, 5280.313559237294, 5521.67479804846, 6567.507436942225, 6576.375244351802, 6528.151340325751, 6590.670466476657, 6602.933970660304, 6603.306652801459, 6603.438707709803, 6045.395460453963, 6063.090536419572, 6065.272167339904, 6071.969640151771, 6074.51403887688, 28735.994240201493, 29475.705242947726, 29740.70259297401, 29797.0, 29817.552736708967, 2191.430228140695, 2267.9092836286527, 2281.041962375806, 2289.97710022901, 2296.5406918616345, 18684.81315186858, 19194.904025479922, 19268.132934018002, 19276.614467710708, 19309.51726206842, 1962.6663467210608, 2227.2093406840663, 2242.192810588187, 2288.8054515366216, 2296.0, 161786.19106904507, 166065.16967415085, 172628.0, 172709.0, 172839.0, 13331.40008699611, 14029.579112626638, 14348.282585870666, 15126.924365378103, 15216.695666086838, 20188.596228075356, 20225.190992360436, 20243.0, 20256.797432025785, 20280.89859550693, 8195.46729462583, 9453.574589143505, 9552.522373881417, 9742.610295588098, 9837.55730992107, 8788.736337909873, 8911.598978046004, 8947.686830960885, 8969.327300452493, 9020.909790902138, 8177.855100285965, 8633.784155396039, 9012.81974360509, 9090.590923408463, 9180.44917304964, 2567.2213194022142, 2573.2923446052487, 2589.5498520828396, 2594.597837335216, 2686.449277898026, 966.3529916028662, 970.8203982263304, 989.6882482018141, 997.4540508492234, 1012.8885822559513, 21228.575428491342, 21229.46926326854, 21596.784032159478, 21757.0, 22351.0, 916.4455504419864, 990.5740531571349, 1012.2509343086064, 1046.1212581431635, 1046.602291129366, 11131.332120072822, 11161.386123763108, 11319.094472442184, 13826.654333641538, 15287.082775033534, 545591.0, 551527.0, 569486.0, 578362.0, 583767.0, 134931.0, 137164.62835371518, 139614.0, 142986.28506857398, 143073.0, 1216.154772433168, 1218.8659247482947, 1221.3221661977689, 1245.769532636465, 1253.974920501603, 23181.768182318636, 24258.272251832474, 24314.878425608465, 24421.14525991582, 24489.755102448747, 43232.0, 67872.0, 70850.0, 71228.64385678039, 71968.0, 89045.0, 89334.21331573202, 90735.0, 92574.53712731393, 92910.0, 10649.680509584725, 11619.0704743622, 11755.177137600285, 11856.11079169083, 11937.865902739226, 13896.096753710977, 18071.819281807013, 18397.90801045986, 18555.35056273024, 18742.625147497245, 14518.346674399467, 14918.477853275082, 15234.695306093594, 15265.313060912507, 15457.84542154564, 11770.234934729215, 12878.935605321913, 13145.53990610324, 13189.208647481182, 13689.247091410069, 15317.540473785804, 15576.688466230838, 15637.843621564094, 15664.138472384127, 15679.686406271583, 11818.113641476752, 11840.881591184323, 11844.1709080367, 11857.822132667845, 11873.881261187278, 14033.0, 14947.701045979236, 15117.848821512083, 15140.318685659393, 15175.24123793828], "value": 11666.562725761929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.0931894268726, 1434.239852877975, 1456.5557504960982, 1463.3195564062714, 1478.5786050975473, 11438.828417573723, 13170.473181072757, 13768.862311376883, 13771.931140344297, 13798.689124533179, 11546.595869144581, 11590.130740194478, 11681.649550513499, 12635.936820315885, 13150.210987340743, 2702.6216329713807, 2746.148693904886, 2766.0457142285877, 2768.6539182602173, 2781.6244806951004, 2712.3083613678486, 2751.17464760572, 2751.5047291487526, 2753.6282601848793, 2762.502749505088, 2696.3798326384845, 2722.7821774258014, 2734.0567504211017, 2739.205630367191, 2740.6300149479766, 1983.4049785064424, 2344.15610380263, 2347.460084180631, 2678.7723043541323, 2687.8924843006257, 2333.918312859059, 2351.3298709867604, 2358.2217868103658, 2571.2029270926073, 2671.2787547362104, 13195.736085278337, 13311.667208319772, 13314.800277995837, 13315.267660278681, 13317.600471985856, 7314.45141614381, 7573.129090154647, 7608.657610407545, 7611.961940190264, 7618.657160427794, 4537.160625284333, 4842.12841688496, 4915.705057696549, 4918.114739346916, 4939.678920870132, 1363.277462944641, 1387.1676993803737, 1389.3192335755507, 1412.3503188533286, 1414.3988804757937, 19753.703694444594, 19891.7016244755, 19895.900520497304, 19954.301599444094, 20082.497937551532, 6747.392734653905, 6849.794506164823, 6906.136732908387, 7042.802723536908, 7079.433645308363, 16366.099864507567, 16369.345226191057, 16487.67024659524, 16528.83471165273, 16530.83469165293, 6833.487488438387, 7670.0, 7702.114256860421, 7713.3443657288635, 7721.150673425918, 6472.482201423874, 6720.596764194164, 6860.862782744317, 6862.210845752703, 6883.208431030495, 5201.557867581298, 5367.865803354947, 5378.435264297271, 5400.648957817729, 5459.754311056012, 1650.4718490083299, 1680.4790514940407, 1690.0620155813397, 1695.8134605193418, 1698.1424380687674, 664.7673314339943, 706.8974998625171, 854.6282367170177, 856.0198572634299, 867.926226270774, 7260.346568808833, 7437.888431673529, 7600.543967361976, 7661.348785353307, 7896.763097106981, 875.2123089219754, 949.9192568631605, 986.3243678080538, 987.6345752071779, 1027.660871912267, 4742.739149346819, 4897.7795999180125, 4915.16442204826, 4941.209406495015, 5245.318108645928, 17203.741943870853, 17905.552361191076, 17958.910205449152, 17993.64012719738, 18034.0, 17448.389306374214, 17561.824381756018, 17708.937463752216, 17734.29062837498, 17746.822531774516, 8876.20114189726, 9449.338546301693, 9474.194693450996, 9486.810263794685, 9518.238540916711, 6523.380278873499, 7670.2329767025085, 7858.44990850635, 8369.83260334802, 8472.322214222724, 15144.469943551923, 15641.608959776097, 15999.600009999844, 16223.594410139842, 16248.512544623207, 11567.652970410902, 15730.449434269745, 16422.0, 16568.751468727736, 16621.584460388585, 9794.75513112178, 10514.474276286002, 11610.477528511406, 12333.075019373762, 12340.81488777687, 10466.842997354894, 11025.889741102486, 11887.940560297144, 12113.636590902288, 12317.384130793604, 9824.607015719292, 9915.454649994319, 10130.544125514221, 10184.490775461049, 10436.947815260875, 9538.523073846418, 9636.180924621485, 9993.900060999296, 10073.84889226677, 10218.029287217703, 7202.855942881218, 8856.760053592374, 8898.665200220019, 8910.198082172636, 8965.148310910454, 6530.6081635100145, 6601.240857301472, 6683.9331606685255, 6700.363465470772, 7011.684474198775, 6764.898526522008, 6830.7267709293055, 7122.252163522756, 7162.427005839416, 7299.626644335096], "value": 6081.509169014077, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.8375925268771, 439.1305215672966, 446.83020452228146, 466.42862493445557, 466.9889688823697, 6336.27132879719, 6387.6806159692005, 6417.454516366103, 6423.807285781422, 6436.613803171807, 5049.9747501262445, 5914.438128377808, 6175.197224360838, 6188.938110618892, 6219.129321894939, 4619.399478067847, 4790.712557246577, 4803.231482962727, 4819.012102518984, 4910.582600478963, 4022.637962583367, 4810.9037819243595, 4863.8784030399165, 4869.074875773592, 4903.215485522317, 4813.879653008667, 4831.9275210871865, 4834.323194752737, 4839.225723884179, 4850.830220942268, 4821.37322148122, 4835.758212089399, 4852.514748525155, 4865.683730557503, 4887.902241955158, 3773.3585290500682, 4264.48826140862, 4333.241682705546, 4340.218760623086, 4361.236783562896, 5827.125931110325, 5992.520598352165, 6051.636901785863, 6122.418370254819, 6131.570790044699, 5265.947340526584, 5654.6324488908085, 5683.318001839806, 5684.943150568482, 5688.63023903445, 5632.831015069554, 5961.433663801975, 6854.554453960526, 6871.244163142101, 6875.48433867457, 5053.974730126362, 5062.468440813698, 5075.822346217901, 5077.959018401219, 5094.337736094321, 6496.837579060514, 7113.221694576304, 7120.92879071213, 7121.145462544476, 7135.464840136959, 4351.369051487517, 4374.518802931675, 4917.721392437992, 4980.003999200175, 5252.343457067866, 4823.710577365369, 5207.895842083137, 5319.494648008433, 5350.892982140335, 5350.9732451337495, 4878.438819619657, 5835.299764028259, 5852.590318677651, 5854.648721076749, 5898.616589921641, 6484.605809750832, 6824.587706146926, 6844.760433384808, 6851.314868513109, 6884.0, 1615.7980252468442, 1816.9182386792627, 1837.8713490055836, 1841.8618603604516, 1842.9631407371778, 1166.8658104318113, 1195.3485350484007, 1307.7972914198315, 1308.4494873574845, 1326.8540460549332, 6007.519398448114, 6312.179416675803, 6325.367463253638, 6365.172527571479, 6379.808605741835, 6941.34057264559, 6941.375276225164, 6971.0, 6985.965070174719, 7003.614801185881, 5642.463965923231, 6223.1287619733275, 6274.749010039639, 6343.175387199727, 6351.936480635227, 2922.824630522133, 3407.880724174642, 3475.5308033415167, 3629.909252268714, 3639.94540081899, 6808.0, 6926.480513961473, 7032.367086962198, 7119.540494198677, 7123.2520585338825, 6133.233345831771, 6737.427318677967, 6954.756583519553, 7017.631561845391, 7049.295070493013, 5270.683758974397, 6586.604803711793, 6591.307912669196, 7135.89296160558, 7150.570965742072, 6963.442924566124, 6968.965155174192, 6991.580505169706, 6994.440444764305, 7036.0, 6535.967320163369, 6693.330666933268, 6880.105586273752, 6900.827479313058, 6942.791716248521, 5904.557158213237, 5952.791652292149, 6849.0, 7171.0, 7179.210286868336, 4070.5929407060244, 4557.794899229476, 5172.275881376535, 5187.740612969412, 5195.506426889439, 6754.5947243165565, 7006.929930700628, 7009.02116600453, 7012.6844292008, 7016.0, 6034.306054803754, 6116.847078823065, 6754.527183097136, 6791.864162716619, 6793.626350550767, 6715.3620406061345, 6747.4264687500145, 6748.223954245324, 6753.257141714309, 6777.864442711019, 6175.783847565313, 6574.9151390020115, 6601.953394447736, 6602.151397608677, 6650.0, 5662.631928924607, 6054.9101161791305, 6185.41238582334, 6284.277308109626, 6636.170478690164, 5628.577856660685, 6056.879477296714, 6294.496440284858, 6551.574147680385, 6623.933760662524], "value": 5050.261416710525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.5919803306215, 813.53221897409, 823.1891586787018, 855.5422848775906, 856.3063918226237, 16619.66760664786, 17004.149792510372, 17092.572685682833, 17223.966562006273, 17405.47783566492, 10947.288426252308, 12835.165714228591, 13531.120477168977, 13859.584212473643, 13981.650458738537, 661.1999480628446, 875.5534677314583, 879.3580686099119, 970.0590427285522, 970.1123472023125, 1008.1682611845221, 1012.8025035118155, 1016.5527168046073, 1016.959321627134, 1017.2675673515057, 1061.867266591676, 1064.675274041417, 1067.588978243378, 1068.4978060311641, 1071.3518321415547, 682.1098466501214, 1036.974075648111, 1063.500154927183, 1063.973400664982, 1064.2816099133092, 514.0259208799305, 517.5057819782099, 518.0674785386327, 518.2356024863334, 741.2476336518438, 17297.481075567754, 17544.736828947574, 17546.736798948026, 17565.121743912754, 17569.912150439166, 17002.0, 17305.307787688478, 17917.0, 17957.64084718298, 18147.18337674808, 14654.194019328932, 14792.482263120846, 14820.036697614729, 14969.850301497063, 15077.095374277578, 3624.691901188402, 3627.8934924847913, 3638.0177352115047, 3659.2864391443677, 3662.3224703429682, 101266.49366753119, 117645.82354176434, 147149.52850471466, 152576.2371188137, 154838.0, 11067.114630829676, 11627.76744465106, 12598.307093109783, 14164.504242351468, 14167.929160354131, 25977.350566235997, 26406.471870562866, 26623.201303960912, 26926.461470770868, 27029.86485067562, 7807.570583617956, 8086.474379165335, 8106.0, 8107.675692972215, 8128.024637043474, 3882.6699730523223, 3963.564007959122, 3969.603039696065, 3975.7018223633345, 3980.8208630611693, 4534.7052441591195, 4593.655475839326, 4600.42494688164, 4623.329617205553, 4635.559621835921, 912.2884150362728, 924.3621900888365, 943.9339246252833, 1006.4011912911806, 1032.4682788363998, 642.6568471893764, 645.6578013652771, 659.1167835100988, 675.2841987493273, 689.4449967775988, 9698.466584337788, 10827.296225745458, 11063.0, 11928.687844337115, 12344.506219751109, 603.3182503770711, 620.419907386595, 621.3724138620026, 647.9546431749826, 664.9567778094408, 5038.244263360525, 5208.635395522353, 5287.656302340336, 5328.600354973392, 5446.591505637014, 139303.0, 141596.29201853924, 142151.0, 145351.0, 147233.0, 90136.09863901408, 119547.0, 121892.0, 122273.0, 126076.0, 76459.61770191003, 101647.0, 102365.0, 102567.0, 110719.0, 9852.704418867446, 10636.468176591294, 10933.61732339396, 12159.209651372632, 12562.309073000708, 39010.80494597509, 44296.0, 44930.0, 47726.76136619295, 48574.75712621414, 82733.0, 86762.0, 88386.55806721182, 89840.0, 92819.53590232276, 16713.832861671228, 16929.746053808958, 17138.828611713725, 17217.139143042546, 17276.740848887024, 18855.90572047131, 19228.519287017938, 19580.902095489433, 19607.705884411458, 19613.215471380983, 17396.82603173952, 17712.645747084727, 17842.91078544599, 17859.196336164645, 18087.457376278733, 14211.0, 17431.302747889942, 17493.650126997643, 18241.179146938684, 18435.26258949627, 16449.753253700965, 17141.57146071358, 17539.070702223, 17619.471415857544, 18050.368237111637, 11606.825897611374, 11805.763884722086, 12060.457279422271, 12437.253764774143, 12732.108752387614, 12947.741045178855, 13187.340632968506, 13259.403326850508, 13621.182729036289, 13997.93001035029], "value": 8643.546714333033, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 121.0, 119.0, 121.0, 168.0, 167.0, 114.0, 116.0, 139.0], "value": 128.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 115.0, 148.0, 135.0, 116.0, 149.0, 132.0, 105.0, 157.0, 113.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 204.0, 211.0, 233.0, 259.0, 285.0, 288.0, 200.0, 201.0, 200.0], "value": 229.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 85.0, 72.0, 128.0, 82.0, 85.0, 86.0, 89.0, 95.0, 58.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 107.0, 72.0, 71.0, 75.0, 107.0, 69.0, 72.0, 107.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 92.0, 90.0, 91.0, 88.0, 89.0, 90.0, 89.0, 89.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 152.0, 98.0, 99.0, 97.0, 98.0, 120.0, 153.0, 128.0, 99.0], "value": 114.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 95.0, 92.0, 91.0, 90.0, 90.0, 93.0, 116.0, 147.0], "value": 100.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 49.0, 54.0, 53.0, 53.0, 54.0, 53.0, 54.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 63.0, 62.0, 71.0, 66.0, 59.0, 60.0, 59.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 83.0, 83.0, 83.0, 82.0, 96.0, 82.0, 92.0, 126.0, 100.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 152.0, 147.0, 144.0, 144.0, 146.0, 145.0, 137.0, 150.0, 130.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 217.0, 189.0, 178.0, 184.0, 211.0, 233.0, 189.0, 181.0], "value": 194.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 92.0, 97.0, 73.0, 62.0, 65.0, 63.0, 82.0, 95.0], "value": 77.1, "unit": "score"}]}]} 11:11:46 INFO - Return code: 0 11:11:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:11:46 INFO - # TBPL SUCCESS # 11:11:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:11:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:11:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:11:46 INFO - 2017-06-08 11:11:46.482 screenresolution[1670:11042] starting screenresolution argv=screenresolution get 11:11:46 INFO - 2017-06-08 11:11:46.484 screenresolution[1670:11042] Display 0: 1600x1200x32@60 11:11:46 INFO - 2017-06-08 11:11:46.490 screenresolution[1671:11045] starting screenresolution argv=screenresolution list 11:11:46 INFO - Available Modes on Display 0 11:11:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:11:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:11:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:11:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:11:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:11:46 INFO - Intel Iris: 11:11:46 INFO - Chipset Model: Intel Iris 11:11:46 INFO - Type: GPU 11:11:46 INFO - Bus: Built-In 11:11:46 INFO - VRAM (Dynamic, Max): 1536 MB 11:11:46 INFO - Vendor: Intel (0x8086) 11:11:46 INFO - Device ID: 0x0a2e 11:11:46 INFO - Revision ID: 0x0009 11:11:46 INFO - Displays: 11:11:46 INFO - Display: 11:11:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:11:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:11:46 INFO - Display Serial Number: 1600x1200 60 11:11:46 INFO - Main Display: Yes 11:11:46 INFO - Mirror: Off 11:11:46 INFO - Online: Yes 11:11:46 INFO - Rotation: Supported 11:11:46 INFO - Return code: 0 11:11:46 INFO - Running post-action listener: _resource_record_post_action 11:11:46 INFO - [mozharness: 2017-06-08 18:11:46.726681Z] Finished run-tests step (success) 11:11:46 INFO - Running post-run listener: _resource_record_post_run 11:11:46 INFO - Total resource usage - Wall time: 1259s; CPU: 53.0%; Read bytes: 40369152; Write bytes: 573454336; Read time: 410; Write time: 2111 11:11:46 INFO - TinderboxPrint: CPU usage
53.1% 11:11:46 INFO - TinderboxPrint: I/O read bytes / time
40,369,152 / 410 11:11:46 INFO - TinderboxPrint: I/O write bytes / time
573,454,336 / 2,111 11:11:46 INFO - TinderboxPrint: CPU idle
2,362.3 (46.9%) 11:11:46 INFO - TinderboxPrint: CPU system
1,016.5 (20.2%) 11:11:46 INFO - TinderboxPrint: CPU user
1,658.9 (32.9%) 11:11:46 INFO - TinderboxPrint: Swap in / out
286,646,272 / 0 11:11:46 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 294912; Write bytes: 251746304; Read time: 6; Write time: 544 11:11:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:11:46 INFO - run-tests - Wall time: 1238s; CPU: 53.0%; Read bytes: 36424704; Write bytes: 320069632; Read time: 377; Write time: 1561 11:11:47 INFO - Running post-run listener: _upload_blobber_files 11:11:47 INFO - Blob upload gear active. 11:11:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:11:47 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 11:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:11:47 INFO - (blobuploader) - INFO - Open directory for files ... 11:11:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:11:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:11:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:48 INFO - (blobuploader) - INFO - Done attempting. 11:11:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:11:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:11:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:49 INFO - (blobuploader) - INFO - Done attempting. 11:11:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:11:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:11:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:50 INFO - (blobuploader) - INFO - Done attempting. 11:11:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:11:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:11:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:52 INFO - (blobuploader) - INFO - Done attempting. 11:11:52 INFO - (blobuploader) - INFO - Iteration through files over. 11:11:52 INFO - Return code: 0 11:11:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:11:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:11:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e64cb5586ed84fde37a71fb4601c1770e3f28729a4d8a9a7bf367d4318ad7e954bb8729db465deb1900a15315d312d8b4ec7d5d7ea72d3cb31f8caf7b8b12783", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8492f47b7b7fdf414bb7456ba00f98c600ecd500a4024d59c43a3cde6d205994449a22ba926e9796f163e0558ae6ad00792eef15b0c19fae3b559aa8751736cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe7e76b3ba56c7fc6303300defb41f6074f1e9b24d2e6872548b593e55de58238000c696843893964ef3dd2d03625363b532721c682755f7c506339f697cd613", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c5d7f7d3dac4753f94e6ed76a3570ced4bf9cb1212ecb173c5143f2f79dac0c9585ff773f0c081ad514eb7a3eda55f65b25c784eb843d8613a3844f2ddc6222"} 11:11:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:11:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:11:52 INFO - Contents: 11:11:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e64cb5586ed84fde37a71fb4601c1770e3f28729a4d8a9a7bf367d4318ad7e954bb8729db465deb1900a15315d312d8b4ec7d5d7ea72d3cb31f8caf7b8b12783", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8492f47b7b7fdf414bb7456ba00f98c600ecd500a4024d59c43a3cde6d205994449a22ba926e9796f163e0558ae6ad00792eef15b0c19fae3b559aa8751736cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe7e76b3ba56c7fc6303300defb41f6074f1e9b24d2e6872548b593e55de58238000c696843893964ef3dd2d03625363b532721c682755f7c506339f697cd613", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c5d7f7d3dac4753f94e6ed76a3570ced4bf9cb1212ecb173c5143f2f79dac0c9585ff773f0c081ad514eb7a3eda55f65b25c784eb843d8613a3844f2ddc6222"} 11:11:52 INFO - Running post-run listener: copy_logs_to_upload_dir 11:11:52 INFO - Copying logs to upload dir... 11:11:52 INFO - mkdir: /builds/slave/test/build/upload/logs 11:11:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1342.763492 ========= master_lag: 0.69 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2017-06-08 11:11:53.558945) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 11:11:53.566221) ========= 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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e64cb5586ed84fde37a71fb4601c1770e3f28729a4d8a9a7bf367d4318ad7e954bb8729db465deb1900a15315d312d8b4ec7d5d7ea72d3cb31f8caf7b8b12783", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8492f47b7b7fdf414bb7456ba00f98c600ecd500a4024d59c43a3cde6d205994449a22ba926e9796f163e0558ae6ad00792eef15b0c19fae3b559aa8751736cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe7e76b3ba56c7fc6303300defb41f6074f1e9b24d2e6872548b593e55de58238000c696843893964ef3dd2d03625363b532721c682755f7c506339f697cd613", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c5d7f7d3dac4753f94e6ed76a3570ced4bf9cb1212ecb173c5143f2f79dac0c9585ff773f0c081ad514eb7a3eda55f65b25c784eb843d8613a3844f2ddc6222"} build_url:https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011116 build_url: 'https://queue.taskcluster.net/v1/task/eRzT7t3nRvGOO6-JmuD3Pg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e64cb5586ed84fde37a71fb4601c1770e3f28729a4d8a9a7bf367d4318ad7e954bb8729db465deb1900a15315d312d8b4ec7d5d7ea72d3cb31f8caf7b8b12783", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8492f47b7b7fdf414bb7456ba00f98c600ecd500a4024d59c43a3cde6d205994449a22ba926e9796f163e0558ae6ad00792eef15b0c19fae3b559aa8751736cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe7e76b3ba56c7fc6303300defb41f6074f1e9b24d2e6872548b593e55de58238000c696843893964ef3dd2d03625363b532721c682755f7c506339f697cd613", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c5d7f7d3dac4753f94e6ed76a3570ced4bf9cb1212ecb173c5143f2f79dac0c9585ff773f0c081ad514eb7a3eda55f65b25c784eb843d8613a3844f2ddc6222"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 11:11:53.605966) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 11:11:53.606308) ========= 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.0HyBiHC9cI/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.g6AYE5Xksh/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.005198 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 11:11:53.654582) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 11:11:53.654935) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 11:11:53.655291) ========= ========= Total master_lag: 0.83 =========