builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0243 starttime: 1496281542.62 results: success (0) revision: 7fb3d9dfa8e684d5258a3d96b7176a1fa95fe205 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.620426) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.620895) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.621190) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.646739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.647125) ========= 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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-31 18:45:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 197M=0s 2017-05-31 18:45:42 (197 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140754 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.811017) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.811297) ========= 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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.026683 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.860248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:42.860608) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7fb3d9dfa8e684d5258a3d96b7176a1fa95fe205 --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 7fb3d9dfa8e684d5258a3d96b7176a1fa95fe205 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zuD0Qv7qfX/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.35OlLvIv3J/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-31 18:45:42,946 truncating revision to first 12 chars 2017-05-31 18:45:42,946 Setting DEBUG logging. 2017-05-31 18:45:42,946 attempt 1/10 2017-05-31 18:45:42,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7fb3d9dfa8e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 18:45:43,299 unpacking tar archive at: mozilla-central-7fb3d9dfa8e6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.630804 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:43.512721) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:43.513021) ========= script_repo_revision: 7fb3d9dfa8e684d5258a3d96b7176a1fa95fe205 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:43.513419) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:43.513755) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 18:45:43.532596) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-31 18:45:43.532941) ========= /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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:45:43 INFO - MultiFileLogger online at 20170531 18:45:43 in /builds/slave/test 18:45:43 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 18:45:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:45:43 INFO - {'append_to_log': False, 18:45:43 INFO - 'base_work_dir': '/builds/slave/test', 18:45:43 INFO - 'blob_upload_branch': 'Firefox', 18:45:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:45:43 INFO - 'branch': 'Firefox', 18:45:43 INFO - 'buildbot_json_path': 'buildprops.json', 18:45:43 INFO - 'config_files': ('talos/mac_config.py',), 18:45:43 INFO - 'default_actions': ('clobber', 18:45:43 INFO - 'read-buildbot-config', 18:45:43 INFO - 'download-and-extract', 18:45:43 INFO - 'populate-webroot', 18:45:43 INFO - 'create-virtualenv', 18:45:43 INFO - 'install', 18:45:43 INFO - 'setup-mitmproxy', 18:45:43 INFO - 'run-tests'), 18:45:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:45:43 INFO - 'download_minidump_stackwalk': True, 18:45:43 INFO - 'download_symbols': 'ondemand', 18:45:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:45:43 INFO - 'tooltool.py': '/tools/tooltool.py', 18:45:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:45:43 INFO - '/tools/misc-python/virtualenv.py')}, 18:45:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:45:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:45:43 INFO - 'gecko_profile': False, 18:45:43 INFO - 'gecko_profile_interval': 0, 18:45:43 INFO - 'installer_path': 'installer.exe', 18:45:43 INFO - 'log_level': 'info', 18:45:43 INFO - 'log_name': 'talos', 18:45:43 INFO - 'log_to_console': True, 18:45:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:45:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:45:43 INFO - 'opt_config_files': (), 18:45:43 INFO - 'pip_index': False, 18:45:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:45:43 INFO - 'cmd': ('bash', 18:45:43 INFO - '-c', 18:45:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:45:43 INFO - 'enabled': True, 18:45:43 INFO - 'halt_on_failure': False, 18:45:43 INFO - 'name': 'check_screen_resolution'},), 18:45:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:45:43 INFO - 'cmd': ('bash', 18:45:43 INFO - '-c', 18:45:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:45:43 INFO - 'enabled': True, 18:45:43 INFO - 'halt_on_failure': False, 18:45:43 INFO - 'name': 'check_screen_resolution'},), 18:45:43 INFO - 'run_cmd_checks_enabled': True, 18:45:43 INFO - 'suite': 'dromaeojs-e10s', 18:45:43 INFO - 'system_bits': '32', 18:45:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:45:43 INFO - 'title': 't-yosemite-r7-0243', 18:45:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:45:43 INFO - 'use_talos_json': True, 18:45:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:45:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:45:43 INFO - 'work_dir': 'build'} 18:45:43 INFO - [mozharness: 2017-06-01 01:45:43.728805Z] Running clobber step. 18:45:43 INFO - Running pre-action listener: _resource_record_pre_action 18:45:43 INFO - Running main action method: clobber 18:45:43 INFO - rmtree: /builds/slave/test/build 18:45:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:45:46 INFO - Running post-action listener: _resource_record_post_action 18:45:46 INFO - [mozharness: 2017-06-01 01:45:46.271519Z] Finished clobber step (success) 18:45:46 INFO - [mozharness: 2017-06-01 01:45:46.271643Z] Running read-buildbot-config step. 18:45:46 INFO - Running pre-action listener: _resource_record_pre_action 18:45:46 INFO - Running main action method: read_buildbot_config 18:45:46 INFO - Using buildbot properties: 18:45:46 INFO - { 18:45:46 INFO - "project": "", 18:45:46 INFO - "product": "firefox", 18:45:46 INFO - "who": "ryanvm@gmail.com", 18:45:46 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:45:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:45:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:45:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:45:46 INFO - "stage_platform": "macosx64", 18:45:46 INFO - "basedir": "/builds/slave/test", 18:45:46 INFO - "buildnumber": 6, 18:45:46 INFO - "platform": "yosemite_r7", 18:45:46 INFO - "parent_task_id": "L3vt00xtTVy66ZLcyENLqg", 18:45:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:45:46 INFO - "slavebuilddir": "test", 18:45:46 INFO - "taskId": "YP-vJsSERbmzOa-kBhn56w", 18:45:46 INFO - "branch": "mozilla-central", 18:45:46 INFO - "script_repo_revision": "production", 18:45:46 INFO - "revision": "7fb3d9dfa8e684d5258a3d96b7176a1fa95fe205", 18:45:46 INFO - "slavename": "t-yosemite-r7-0243", 18:45:46 INFO - "repo_path": "mozilla-central" 18:45:46 INFO - } 18:45:46 INFO - Finding installer, test and symbols from parent task. 18:45:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YP-vJsSERbmzOa-kBhn56w'}, attempt #1 18:45:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YP-vJsSERbmzOa-kBhn56w'}, attempt #1 18:45:47 INFO - Task dependencies: L60C-PS8Te2oC3xJYlJaQQ L3vt00xtTVy66ZLcyENLqg 18:45:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg'}, attempt #1 18:45:47 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:45:47 INFO - Set test_url: https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/test_packages.json 18:45:47 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/target.crashreporter-symbols.zip 18:45:47 INFO - Running post-action listener: _resource_record_post_action 18:45:47 INFO - [mozharness: 2017-06-01 01:45:47.651248Z] Finished read-buildbot-config step (success) 18:45:47 INFO - [mozharness: 2017-06-01 01:45:47.651352Z] Running download-and-extract step. 18:45:47 INFO - Running pre-action listener: _resource_record_pre_action 18:45:47 INFO - Running main action method: download_and_extract 18:45:47 INFO - mkdir: /builds/slave/test/build/tests 18:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:47 INFO - trying https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:45:47 INFO - Downloading https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/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 18:45:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/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 18:45:48 INFO - Downloaded 1325 bytes. 18:45:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:45:48 INFO - Using the following test package requirements: 18:45:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:45:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:45:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:45:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:45:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'jsshell-mac.zip'], 18:45:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:45:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:45:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:45:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:45:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:45:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:45:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:45:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:45:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:45:48 INFO - Fetch https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:45:50 INFO - Content-Length response header: 57629951 18:45:50 INFO - Bytes received: 57629951 18:46:08 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 18:46:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:46:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:46:08 INFO - Fetch https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:46:10 INFO - Content-Length response header: 57629951 18:46:10 INFO - Bytes received: 57629951 18:46:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:46:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:46:27 INFO - Fetch https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:46:28 INFO - Content-Length response header: 14447425 18:46:28 INFO - Bytes received: 14447425 18:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:28 INFO - trying https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:46:28 INFO - Downloading https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:46:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/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 18:46:30 INFO - Downloaded 67889675 bytes. 18:46:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:46:30 INFO - mkdir: /builds/slave/test/properties 18:46:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:46:30 INFO - Writing to file /builds/slave/test/properties/build_url 18:46:30 INFO - Contents: 18:46:30 INFO - build_url:https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:46:30 INFO - Running post-action listener: _resource_record_post_action 18:46:30 INFO - Running post-action listener: set_extra_try_arguments 18:46:30 INFO - [mozharness: 2017-06-01 01:46:30.758767Z] Finished download-and-extract step (success) 18:46:30 INFO - [mozharness: 2017-06-01 01:46:30.758916Z] Running populate-webroot step. 18:46:30 INFO - Running pre-action listener: _resource_record_pre_action 18:46:30 INFO - Running main action method: populate_webroot 18:46:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:46:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:46:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:46:30 INFO - '--tppagecycles', 18:46:30 INFO - '7'], 18:46:30 INFO - 'tests': ['tsvgm']}}, 18:46:30 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:46:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:46:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:46:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:46:30 INFO - 'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:46:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:46:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:46:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:46:30 INFO - 'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['damp', 'tps']}, 18:46:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:46:30 INFO - 'tests': ['damp', 'tps']}, 18:46:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['dromaeo_dom']}, 18:46:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:46:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:46:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:46:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:46:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:46:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['a11yr', 18:46:30 INFO - 'ts_paint', 18:46:30 INFO - 'tpaint', 18:46:30 INFO - 'sessionrestore', 18:46:30 INFO - 'sessionrestore_no_auto_restore', 18:46:30 INFO - 'tabpaint']}, 18:46:30 INFO - 'other-e10s': {'tests': ['a11yr', 18:46:30 INFO - 'ts_paint', 18:46:30 INFO - 'tpaint', 18:46:30 INFO - 'sessionrestore', 18:46:30 INFO - 'sessionrestore_no_auto_restore', 18:46:30 INFO - 'tabpaint']}, 18:46:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:46:30 INFO - 'ts_paint', 18:46:30 INFO - 'tpaint', 18:46:30 INFO - 'sessionrestore', 18:46:30 INFO - 'sessionrestore_no_auto_restore', 18:46:30 INFO - 'tabpaint']}, 18:46:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:46:30 INFO - 'ts_paint', 18:46:30 INFO - 'tpaint', 18:46:30 INFO - 'sessionrestore', 18:46:30 INFO - 'sessionrestore_no_auto_restore', 18:46:30 INFO - 'tabpaint']}, 18:46:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['a11yr', 18:46:30 INFO - 'ts_paint', 18:46:30 INFO - 'tpaint', 18:46:30 INFO - 'sessionrestore', 18:46:30 INFO - 'sessionrestore_no_auto_restore', 18:46:30 INFO - 'tabpaint']}, 18:46:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['a11yr', 18:46:30 INFO - 'ts_paint', 18:46:30 INFO - 'tpaint', 18:46:30 INFO - 'sessionrestore', 18:46:30 INFO - 'sessionrestore_no_auto_restore', 18:46:30 INFO - 'tabpaint']}, 18:46:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:46:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:46:30 INFO - 'bloom_basic_ref']}, 18:46:30 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:46:30 INFO - 'talos_options': ['--mitmproxy', 18:46:30 INFO - 'mitmproxy-recording-1.mp', 18:46:30 INFO - '--firstNonBlankPaint'], 18:46:30 INFO - 'tests': ['Quantum_1']}, 18:46:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['tsvgx', 18:46:30 INFO - 'tsvgr_opacity', 18:46:30 INFO - 'tart', 18:46:30 INFO - 'tscrollx', 18:46:30 INFO - 'cart', 18:46:30 INFO - 'tsvg_static']}, 18:46:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:46:30 INFO - 'tsvgr_opacity', 18:46:30 INFO - 'tart', 18:46:30 INFO - 'tscrollx', 18:46:30 INFO - 'cart', 18:46:30 INFO - 'tsvg_static']}, 18:46:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:46:30 INFO - 'talos_options': ['--disable-e10s'], 18:46:30 INFO - 'tests': ['tp5o']}, 18:46:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:46:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:46:30 INFO - 'talos_options': ['--disable-e10s', 18:46:30 INFO - '--xperf_path', 18:46:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:46:30 INFO - 'tests': ['tp5n']}, 18:46:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:46:30 INFO - 'talos_options': ['--xperf_path', 18:46:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:46:30 INFO - 'tests': ['tp5n']}}, 18:46:30 INFO - 'talos.zip': {'path': '', 18:46:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:46:30 INFO - Running post-action listener: _resource_record_post_action 18:46:30 INFO - [mozharness: 2017-06-01 01:46:30.780413Z] Finished populate-webroot step (success) 18:46:30 INFO - [mozharness: 2017-06-01 01:46:30.780549Z] Running create-virtualenv step. 18:46:30 INFO - Running pre-action listener: _resource_record_pre_action 18:46:30 INFO - Running main action method: create_virtualenv 18:46:30 INFO - Creating virtualenv /builds/slave/test/build/venv 18:46:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:46:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:46:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:46:30 INFO - Using real prefix '/tools/python27' 18:46:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:46:31 INFO - Installing distribute.............................................................................................................................................................................................done. 18:46:34 INFO - Installing pip.................done. 18:46:34 INFO - Return code: 0 18:46:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:46:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:46:34 INFO - Reading from file tmpfile_stdout 18:46:34 INFO - Output received: 18:46:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:46:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:46: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')]} 18:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46: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')]} 18:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:46:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:34 INFO - 'HOME': '/Users/cltbld', 18:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:34 INFO - 'LOGNAME': 'cltbld', 18:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:34 INFO - 'MOZ_NO_REMOTE': '1', 18:46:34 INFO - 'NO_EM_RESTART': '1', 18:46:34 INFO - 'PAGER': '/bin/cat', 18:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:34 INFO - 'PWD': '/builds/slave/test', 18:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:34 INFO - 'SHELL': '/bin/bash', 18:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:34 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:34 INFO - 'USER': 'cltbld', 18:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:34 INFO - 'XPC_FLAGS': '0x0', 18:46:34 INFO - 'XPC_SERVICE_NAME': '0', 18:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:46:35 INFO - Cleaning up... 18:46:35 INFO - Return code: 0 18:46:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:46: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')]} 18:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46: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')]} 18:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:46:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:35 INFO - 'HOME': '/Users/cltbld', 18:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:35 INFO - 'LOGNAME': 'cltbld', 18:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:35 INFO - 'MOZ_NO_REMOTE': '1', 18:46:35 INFO - 'NO_EM_RESTART': '1', 18:46:35 INFO - 'PAGER': '/bin/cat', 18:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:35 INFO - 'PWD': '/builds/slave/test', 18:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:35 INFO - 'SHELL': '/bin/bash', 18:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:35 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:35 INFO - 'USER': 'cltbld', 18:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:35 INFO - 'XPC_FLAGS': '0x0', 18:46:35 INFO - 'XPC_SERVICE_NAME': '0', 18:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:35 INFO - Downloading/unpacking psutil>=3.1.1 18:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:46:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:46:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:46:37 INFO - Installing collected packages: psutil 18:46:37 INFO - Running setup.py install for psutil 18:46:37 INFO - building 'psutil._psutil_osx' extension 18:46:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:46:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:46:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:46:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:46:38 INFO - building 'psutil._psutil_posix' extension 18:46:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:46:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:46:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:46:38 INFO - ^ 18:46:38 INFO - 1 warning generated. 18:46:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:46:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:46:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:46:38 INFO - Successfully installed psutil 18:46:38 INFO - Cleaning up... 18:46:38 INFO - Return code: 0 18:46:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:46:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:38 INFO - 'HOME': '/Users/cltbld', 18:46:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:38 INFO - 'LOGNAME': 'cltbld', 18:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:38 INFO - 'MOZ_NO_REMOTE': '1', 18:46:38 INFO - 'NO_EM_RESTART': '1', 18:46:38 INFO - 'PAGER': '/bin/cat', 18:46:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:38 INFO - 'PWD': '/builds/slave/test', 18:46:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:38 INFO - 'SHELL': '/bin/bash', 18:46:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:38 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:38 INFO - 'USER': 'cltbld', 18:46:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:38 INFO - 'XPC_FLAGS': '0x0', 18:46:38 INFO - 'XPC_SERVICE_NAME': '0', 18:46:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:46:40 INFO - Installing collected packages: mozsystemmonitor 18:46:40 INFO - Running setup.py install for mozsystemmonitor 18:46:40 INFO - Successfully installed mozsystemmonitor 18:46:40 INFO - Cleaning up... 18:46:40 INFO - Return code: 0 18:46:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:46: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')]} 18:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46: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')]} 18:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:40 INFO - 'HOME': '/Users/cltbld', 18:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:40 INFO - 'LOGNAME': 'cltbld', 18:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:40 INFO - 'MOZ_NO_REMOTE': '1', 18:46:40 INFO - 'NO_EM_RESTART': '1', 18:46:40 INFO - 'PAGER': '/bin/cat', 18:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:40 INFO - 'PWD': '/builds/slave/test', 18:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:40 INFO - 'SHELL': '/bin/bash', 18:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:40 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:40 INFO - 'USER': 'cltbld', 18:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:40 INFO - 'XPC_FLAGS': '0x0', 18:46:40 INFO - 'XPC_SERVICE_NAME': '0', 18:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:41 INFO - Downloading/unpacking jsonschema==2.5.1 18:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:46:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:46: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 18:46: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 18:46: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 18:46: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 18:46:42 INFO - Downloading functools32-3.2.3-2.tar.gz 18:46:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:46:42 INFO - warning: no files found matching '*.txt' 18:46:42 INFO - no previously-included directories found matching 'build' 18:46:42 INFO - no previously-included directories found matching 'dist' 18:46:42 INFO - no previously-included directories found matching '.git*' 18:46:42 INFO - Installing collected packages: jsonschema, functools32 18:46:42 INFO - Running setup.py install for functools32 18:46:42 INFO - warning: no files found matching '*.txt' 18:46:42 INFO - no previously-included directories found matching 'build' 18:46:42 INFO - no previously-included directories found matching 'dist' 18:46:42 INFO - no previously-included directories found matching '.git*' 18:46:43 INFO - Successfully installed jsonschema functools32 18:46:43 INFO - Cleaning up... 18:46:43 INFO - Return code: 0 18:46:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:46:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:46:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:43 INFO - 'HOME': '/Users/cltbld', 18:46:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:43 INFO - 'LOGNAME': 'cltbld', 18:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:43 INFO - 'MOZ_NO_REMOTE': '1', 18:46:43 INFO - 'NO_EM_RESTART': '1', 18:46:43 INFO - 'PAGER': '/bin/cat', 18:46:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:43 INFO - 'PWD': '/builds/slave/test', 18:46:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:43 INFO - 'SHELL': '/bin/bash', 18:46:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:43 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:43 INFO - 'USER': 'cltbld', 18:46:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:43 INFO - 'XPC_FLAGS': '0x0', 18:46:43 INFO - 'XPC_SERVICE_NAME': '0', 18:46:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:46:43 INFO - Cleaning up... 18:46:43 INFO - Return code: 0 18:46:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:46:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:46:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:43 INFO - 'HOME': '/Users/cltbld', 18:46:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:43 INFO - 'LOGNAME': 'cltbld', 18:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:43 INFO - 'MOZ_NO_REMOTE': '1', 18:46:43 INFO - 'NO_EM_RESTART': '1', 18:46:43 INFO - 'PAGER': '/bin/cat', 18:46:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:43 INFO - 'PWD': '/builds/slave/test', 18:46:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:43 INFO - 'SHELL': '/bin/bash', 18:46:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:43 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:43 INFO - 'USER': 'cltbld', 18:46:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:43 INFO - 'XPC_FLAGS': '0x0', 18:46:43 INFO - 'XPC_SERVICE_NAME': '0', 18:46:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:43 INFO - Downloading/unpacking blobuploader==1.2.4 18:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:44 INFO - Downloading blobuploader-1.2.4.tar.gz 18:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:46:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:46:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:45 INFO - Downloading docopt-0.6.1.tar.gz 18:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:46:45 INFO - Installing collected packages: blobuploader, requests, docopt 18:46:45 INFO - Running setup.py install for blobuploader 18:46:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:46:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:46:46 INFO - Running setup.py install for requests 18:46:46 INFO - Running setup.py install for docopt 18:46:46 INFO - Successfully installed blobuploader requests docopt 18:46:46 INFO - Cleaning up... 18:46:46 INFO - Return code: 0 18:46:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46: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')]} 18:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46: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')]} 18:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:46:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:46 INFO - 'HOME': '/Users/cltbld', 18:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:46 INFO - 'LOGNAME': 'cltbld', 18:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:46 INFO - 'MOZ_NO_REMOTE': '1', 18:46:46 INFO - 'NO_EM_RESTART': '1', 18:46:46 INFO - 'PAGER': '/bin/cat', 18:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:46 INFO - 'PWD': '/builds/slave/test', 18:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:46 INFO - 'SHELL': '/bin/bash', 18:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:46 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:46 INFO - 'USER': 'cltbld', 18:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:46 INFO - 'XPC_FLAGS': '0x0', 18:46:46 INFO - 'XPC_SERVICE_NAME': '0', 18:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-stN9hZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-cMbRb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ZraaV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-OLeKot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-lXqscs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-k7Z2lB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-GlLRSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hMNIgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-4bTMo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:46:47 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-dkEmjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:46:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-wHrB7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:46:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-9JYP3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:46:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-BRo8hY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:46:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-UqHLzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-jCSOQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:46:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-JvHx93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:46:48 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-zfhSJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:46:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:46:48 INFO - Running setup.py install for manifestparser 18:46:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Running setup.py install for mozcrash 18:46:49 INFO - Running setup.py install for mozdebug 18:46:49 INFO - Running setup.py install for mozdevice 18:46:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Running setup.py install for mozfile 18:46:49 INFO - Running setup.py install for mozhttpd 18:46:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Running setup.py install for mozinfo 18:46:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Running setup.py install for mozInstall 18:46:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:46:50 INFO - Running setup.py install for mozleak 18:46:50 INFO - Running setup.py install for mozlog 18:46:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:46:50 INFO - Running setup.py install for moznetwork 18:46:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:46:50 INFO - Running setup.py install for mozprocess 18:46:50 INFO - Running setup.py install for mozprofile 18:46:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:46:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:46:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:46:50 INFO - Running setup.py install for mozrunner 18:46:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:46:50 INFO - Running setup.py install for mozscreenshot 18:46:51 INFO - Running setup.py install for moztest 18:46:51 INFO - Running setup.py install for mozversion 18:46:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:46:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:46:51 INFO - Cleaning up... 18:46:51 INFO - Return code: 0 18:46:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:51 INFO - 'HOME': '/Users/cltbld', 18:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:51 INFO - 'LOGNAME': 'cltbld', 18:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:51 INFO - 'MOZ_NO_REMOTE': '1', 18:46:51 INFO - 'NO_EM_RESTART': '1', 18:46:51 INFO - 'PAGER': '/bin/cat', 18:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:51 INFO - 'PWD': '/builds/slave/test', 18:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:51 INFO - 'SHELL': '/bin/bash', 18:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:51 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:51 INFO - 'USER': 'cltbld', 18:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:51 INFO - 'XPC_FLAGS': '0x0', 18:46:51 INFO - 'XPC_SERVICE_NAME': '0', 18:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:46:51 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-k1XSuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:46:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:46:51 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-cPlivA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:46:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:46:51 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-bstBxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:46:52 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)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-n3hCg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:46:52 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)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-iT5eHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-m1xIjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Ctvnau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-9uGYH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:46:52 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)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-zixbRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-kNU4k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-3F7eCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:46:52 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-kEexKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:46:53 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-mtyh7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:46:53 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)) 18:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:46:53 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-CXJQ76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:53 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-02HCme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:46:53 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-TwHAcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:46:53 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-3yh66k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:46:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:55 INFO - Downloading blessings-1.6.tar.gz 18:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:46:56 INFO - Installing collected packages: blessings 18:46:56 INFO - Running setup.py install for blessings 18:46:56 INFO - Successfully installed blessings 18:46:56 INFO - Cleaning up... 18:46:56 INFO - Return code: 0 18:46:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:46:56 INFO - Reading from file tmpfile_stdout 18:46:56 INFO - Current package versions: 18:46:56 INFO - blessings == 1.6 18:46:56 INFO - blobuploader == 1.2.4 18:46:56 INFO - docopt == 0.6.1 18:46:56 INFO - functools32 == 3.2.3-2 18:46:56 INFO - jsonschema == 2.5.1 18:46:56 INFO - manifestparser == 1.1 18:46:56 INFO - mozInstall == 1.12 18:46:56 INFO - mozcrash == 1.0 18:46:56 INFO - mozdebug == 0.1 18:46:56 INFO - mozdevice == 0.51 18:46:56 INFO - mozfile == 1.2 18:46:56 INFO - mozhttpd == 0.7 18:46:56 INFO - mozinfo == 0.9 18:46:56 INFO - mozleak == 0.1 18:46:56 INFO - mozlog == 3.4 18:46:56 INFO - moznetwork == 0.27 18:46:56 INFO - mozprocess == 0.25 18:46:56 INFO - mozprofile == 0.28 18:46:56 INFO - mozrunner == 6.13 18:46:56 INFO - mozscreenshot == 0.1 18:46:56 INFO - mozsystemmonitor == 0.3 18:46:56 INFO - moztest == 0.8 18:46:56 INFO - mozversion == 1.4 18:46:56 INFO - psutil == 3.1.1 18:46:56 INFO - requests == 1.2.3 18:46:56 INFO - wsgiref == 0.1.2 18:46:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46:56 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')]} 18:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:56 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')]} 18:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:56 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 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:56 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 18:46:56 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 18:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:56 INFO - 'HOME': '/Users/cltbld', 18:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:56 INFO - 'LOGNAME': 'cltbld', 18:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:56 INFO - 'MOZ_NO_REMOTE': '1', 18:46:56 INFO - 'NO_EM_RESTART': '1', 18:46:56 INFO - 'PAGER': '/bin/cat', 18:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:56 INFO - 'PWD': '/builds/slave/test', 18:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:56 INFO - 'SHELL': '/bin/bash', 18:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:56 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:56 INFO - 'USER': 'cltbld', 18:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:56 INFO - 'XPC_FLAGS': '0x0', 18:46:56 INFO - 'XPC_SERVICE_NAME': '0', 18:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:56 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)) 18:46:56 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)) 18:46:56 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)) 18:46:56 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)) 18:46:56 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)) 18:46:56 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)) 18:46:56 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)) 18:46:56 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)) 18:46:56 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)) 18:46:56 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:46:56 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 18:46:56 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 18:46:56 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 18:46:56 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 18:46:58 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:46:58 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)) 18:46:58 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)) 18:46:58 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)) 18:46:58 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)) 18:46:58 INFO - Installing collected packages: marionette-driver 18:46:58 INFO - Running setup.py install for marionette-driver 18:46:58 INFO - Successfully installed marionette-driver 18:46:58 INFO - Cleaning up... 18:46:58 INFO - Return code: 0 18:46:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:58 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 0x10d882c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab90496c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, '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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 18:46:58 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 18:46:58 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 18:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:58 INFO - 'HOME': '/Users/cltbld', 18:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:58 INFO - 'LOGNAME': 'cltbld', 18:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:58 INFO - 'MOZ_NO_REMOTE': '1', 18:46:58 INFO - 'NO_EM_RESTART': '1', 18:46:58 INFO - 'PAGER': '/bin/cat', 18:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:58 INFO - 'PWD': '/builds/slave/test', 18:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:58 INFO - 'SHELL': '/bin/bash', 18:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:46:58 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:58 INFO - 'USER': 'cltbld', 18:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:58 INFO - 'XPC_FLAGS': '0x0', 18:46:58 INFO - 'XPC_SERVICE_NAME': '0', 18:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:46:58 INFO - Cleaning up... 18:46:58 INFO - Return code: 0 18:46:58 INFO - Running post-action listener: _resource_record_post_action 18:46:58 INFO - Running post-action listener: _start_resource_monitoring 18:46:58 INFO - Starting resource monitoring. 18:46:58 INFO - [mozharness: 2017-06-01 01:46:58.854138Z] Finished create-virtualenv step (success) 18:46:58 INFO - [mozharness: 2017-06-01 01:46:58.855164Z] Running install step. 18:46:58 INFO - Running pre-action listener: _resource_record_pre_action 18:46:58 INFO - Running main action method: install 18:46:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:46:59 INFO - Reading from file tmpfile_stdout 18:46:59 INFO - Detecting whether we're running mozinstall >=1.0... 18:46:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:46:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:46:59 INFO - Reading from file tmpfile_stdout 18:46:59 INFO - Output received: 18:46:59 INFO - Usage: mozinstall [options] installer 18:46:59 INFO - Options: 18:46:59 INFO - -h, --help show this help message and exit 18:46:59 INFO - -d DEST, --destination=DEST 18:46:59 INFO - Directory to install application into. [default: 18:46:59 INFO - "/builds/slave/test"] 18:46:59 INFO - --app=APP Application being installed. [default: firefox] 18:46:59 INFO - mkdir: /builds/slave/test/build/application 18:46:59 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'] 18:46:59 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 18:47:20 INFO - Reading from file tmpfile_stdout 18:47:20 INFO - Output received: 18:47:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:47:20 INFO - Running post-action listener: _resource_record_post_action 18:47:20 INFO - [mozharness: 2017-06-01 01:47:20.590821Z] Finished install step (success) 18:47:20 INFO - [mozharness: 2017-06-01 01:47:20.590982Z] Running setup-mitmproxy step. 18:47:20 INFO - Running pre-action listener: _resource_record_pre_action 18:47:20 INFO - Running main action method: setup_mitmproxy 18:47:20 INFO - Skipping: mitmproxy is not required 18:47:20 INFO - Running post-action listener: _resource_record_post_action 18:47:20 INFO - [mozharness: 2017-06-01 01:47:20.591703Z] Finished setup-mitmproxy step (success) 18:47:20 INFO - [mozharness: 2017-06-01 01:47:20.591846Z] Running run-tests step. 18:47:20 INFO - Running pre-action listener: _resource_record_pre_action 18:47:20 INFO - Running main action method: run_tests 18:47:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:47:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:47:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:47:20 INFO - 2017-05-31 18:47:20.606 screenresolution[1635:8361] starting screenresolution argv=screenresolution get 18:47:20 INFO - 2017-05-31 18:47:20.609 screenresolution[1635:8361] Display 0: 1600x1200x32@60 18:47:20 INFO - 2017-05-31 18:47:20.615 screenresolution[1636:8363] starting screenresolution argv=screenresolution list 18:47:20 INFO - Available Modes on Display 0 18:47:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:47:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:47:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:47:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:47:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:47:20 INFO - Intel Iris: 18:47:20 INFO - Chipset Model: Intel Iris 18:47:20 INFO - Type: GPU 18:47:20 INFO - Bus: Built-In 18:47:20 INFO - VRAM (Dynamic, Max): 1536 MB 18:47:20 INFO - Vendor: Intel (0x8086) 18:47:20 INFO - Device ID: 0x0a2e 18:47:20 INFO - Revision ID: 0x0009 18:47:20 INFO - Displays: 18:47:20 INFO - Display: 18:47:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:47:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:47:20 INFO - Display Serial Number: 1600x1200 60 18:47:20 INFO - Main Display: Yes 18:47:20 INFO - Mirror: Off 18:47:20 INFO - Online: Yes 18:47:20 INFO - Rotation: Supported 18:47:20 INFO - Return code: 0 18:47:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:47:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:47:20 INFO - Python 2.7.3 18:47:20 INFO - Return code: 0 18:47:20 INFO - grabbing minidump binary from tooltool 18:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bc60>, '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 0x10d5a0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:47:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:47:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:47:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:47:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:47:20 INFO - Return code: 0 18:47:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:47:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:47:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:47:20 INFO - ENV: RUST_BACKTRACE is now 1 18:47:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:47:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:47:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 18:47:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0243 --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 18:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuD0Qv7qfX/Render', 18:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:20 INFO - 'HOME': '/Users/cltbld', 18:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:20 INFO - 'LOGNAME': 'cltbld', 18:47:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:47:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:20 INFO - 'MOZ_NO_REMOTE': '1', 18:47:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:47:20 INFO - 'NO_EM_RESTART': '1', 18:47:20 INFO - 'PAGER': '/bin/cat', 18:47:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:20 INFO - 'PWD': '/builds/slave/test', 18:47:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:20 INFO - 'RUST_BACKTRACE': '1', 18:47:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:47:20 INFO - 'SHELL': '/bin/bash', 18:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.35OlLvIv3J/Listeners', 18:47:20 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 18:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:20 INFO - 'USER': 'cltbld', 18:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:20 INFO - 'XPC_FLAGS': '0x0', 18:47:20 INFO - 'XPC_SERVICE_NAME': '0', 18:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 18:47:21 INFO - mozversion application_buildid: 20170531172833 18:47:21 INFO - mozversion application_changeset: 7fb3d9dfa8e684d5258a3d96b7176a1fa95fe205 18:47:21 INFO - mozversion application_display_name: Nightly 18:47:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:47:21 INFO - mozversion application_name: Firefox 18:47:21 INFO - mozversion application_remotingname: firefox 18:47:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:47:21 INFO - mozversion application_vendor: Mozilla 18:47:21 INFO - mozversion application_version: 55.0a1 18:47:21 INFO - mozversion platform_buildid: 20170531172833 18:47:21 INFO - mozversion platform_changeset: 7fb3d9dfa8e684d5258a3d96b7176a1fa95fe205 18:47:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:47:21 INFO - mozversion platform_version: 55.0a1 18:47:21 INFO - using testdate: 1496281641 18:47:21 INFO - actual date: 1496281641 18:47:21 INFO - starting webserver on 'localhost:49228' 18:47:21 INFO - SUITE-START | Running 2 tests 18:47:21 INFO - TEST-START | dromaeo_css 18:47:21 INFO - operating with platform_type : mac_ 18:47:21 INFO - Initialising browser for dromaeo_css test... 18:47:21 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmppY1V80/profile 18:47:21 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 18:47:55 INFO - TEST-INFO | 1646: killed by SIGTERM 18:47:55 INFO - Browser initialized. 18:47:55 INFO - Running cycle 1/1 for dromaeo_css test... 18:47:55 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmppY1V80/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:57 INFO - PID 1654 | 2017-05-31 18:47:57.481 plugin-container[1655:9005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 18:47:57 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:57 INFO - PID 1654 | 2017-05-31 18:47:57.482 plugin-container[1655:9005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 18:47:57 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:57 INFO - PID 1654 | 2017-05-31 18:47:57.483 plugin-container[1655:9005] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:57 INFO - PID 1654 | 2017-05-31 18:47:57.931 plugin-container[1656:9050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 18:47:57 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:57 INFO - PID 1654 | 2017-05-31 18:47:57.931 plugin-container[1656:9050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484b, name = 'com.apple.CFPasteboardClient' 18:47:57 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:57 INFO - PID 1654 | 2017-05-31 18:47:57.931 plugin-container[1656:9050] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:48:07 INFO - PID 1654 | RSS: Main: 269570048 18:48:07 INFO - PID 1654 | 18:51:09 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 18:51:10 INFO - PID 1654 | RSS: Main: 270004224 18:51:10 INFO - PID 1654 | 18:54:12 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 18:54:13 INFO - PID 1654 | RSS: Main: 285122560 18:54:13 INFO - PID 1654 | 18:57:15 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 18:57:16 INFO - PID 1654 | RSS: Main: 284315648 18:57:16 INFO - PID 1654 | 19:00:17 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 19:00:18 INFO - PID 1654 | RSS: Main: 289153024 19:00:18 INFO - PID 1654 | 19:03:21 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 19:03:22 INFO - PID 1654 | RSS: Main: 289157120 19:03:22 INFO - PID 1654 | 19:06:23 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 19:06:24 INFO - PID 1654 | RSS: Main: 289128448 19:06:24 INFO - PID 1654 | 19:06:24 INFO - PID 1654 | __start_tp_report 19:06:24 INFO - PID 1654 | _x_x_mozilla_page_load 19:06:24 INFO - PID 1654 | _x_x_mozilla_page_load_details 19:06:24 INFO - PID 1654 | |i|pagename|runs| 19:06:24 INFO - PID 1654 | |0;dojo.html;3572.481990111434;3575.195580994276;3622.6377362263793;3626.818659067046;3643.143861192621;4554.84058057968;4838.830640927568;5469.261649677293;6163.260408750951;6240.43836054755;6190.04054371572;6200.937990620092;6223.688815559227;6231.6572588507615;6236.594621349609;5418.783248670048;5465.672059676413;5481.342238931334;5547.1679248112705;5599.384067752544;4938.950610493903;5398.2712333834825;5584.748686309126;5585.385607583163;5636.323641163066;5406.08096623573;5497.175423686439;5520.861978450531;5582.413846546115;5590.832275031756;4568.817247310104;5388.272583201296;5429.53848922842;5441.347038355423;5475.151351540518;4540.523245059288;5487.6158668893195;5494.80768173116;5495.972520137414;5540.141278101901;6165.383461653843;6254.280757712831;6255.7184926677955;6264.310925798159;6287.49700023997;6523.608583485006;6613.768518101843;6616;6697.7655782047395;6702.798916032478;6837.965810170967;6841.794746157623;6842.794716158523;6842.794716158523;6852.760153394657;6292.622442653455;6297.149884765589;6309.179806625109;6316.557840951135;6319.399657032574;6803.251642319341;6874.381305682413;6884.827879302958;7010.474214433938;7019.89470157948;6256.43692067707;6582;6620.271770105284;6629.966850165815;6653.500987425866;5686.914696279559;6669.666516674147;6694.665266736643;6728.529002969745;6834.316568343225;6632.535722499379;6650.634215118215;6666.466682665375;6668.499862510331;6684.131062961784;6663.13379260693;6665.300143484961;6675.6662166891465;6686.431653309426;6690.19717633887;5234.110201265794;5293.523582877559;5307.893842123136;5315.867103322371;5350.491703288181;4801.95198048022;4809.302651115569;4815.422149342101;4856.150173719585;4870.561649451566;6237.4698150657705;6330.366963303725;6349.238091429058;6354;6367.713452894631;6632.6352050636715;6697.162854643169;6737.225219099866;6823.215330237086;6852.6916288767125;6247.125402443661;6316.084167795644;6320.62076275425;6326.2092238470195;6345.016522438937;5935.258092738408;5953.880922381528;5973.01445261527;5973.093187625418;5983.252093488314;5655.943440565707;6823.55646882951;6929.41100006495;7001.4748893834185;7003;5593.580481463825;6723.562968407038;6807.421369183675;6823.556468829709;6831.726730930707;6183.5671502994355;6552.148220731275;6935.583864968118;6947.131608548932;6980.301969803182;6474.838129046811;6584.144061272004;6646.1027761253545;6660;6673.965535342029;6664;6668.399844014062;6762.256151823197;6770.356816102462;6798;6547.345265473415;6549.639769812706;6550.901736473861;6562.475001999924;6580.309067547839;6406.359364063743;6479.287278399278;6501;6540.738370465128;6571.835704107436;5452;6470;6475.611463312216;6495.675216239264;6517.511186661114;6612.933870661424;6621.933780662324;6623.609042101213;6644.534882558174;6649.268580456049;5345.518903298566;6255.061740738925;6296.968515157395;6388.8083357499345;6437.581557198767 19:06:24 INFO - PID 1654 | |1;ext.html;631.3970158498635;751.2112282103793;797.4896066517429;1247.868973757755;1278.8657190994938;37145.44281835771;37604.87185384439;37618.247635047286;37627.81186094066;37811;37288.81355593225;37605.05987350311;37606.4359034614;37630.4355434668;37719.47366316046;7655.234476552356;7811.882821757678;7968.800779980489;8165.265126138646;8169.060558035812;8158.673653053871;8231.753047408558;8242.629081691308;8259.297959673435;8263.173682631748;8226.218509241613;8250.752477425687;8258.545779982098;8279;8279.668813247463;8168.509889406625;8230.588470576447;8232.62953167109;8239.134890836465;8259.463134896212;7982.560959147245;8017;8039.718609848685;8046.919530804676;8057.758267251992;35250;35323.29353412943;35419;35525.289494209974;35526.75656352015;3531.52324436203;3560.839762210707;3562.3231585998838;3570.214552798406;3578.946315805265;28746.71253287461;29018.564721529194;29051.41897162066;29053.983110591027;29141.271468213254;3024.2439390152463;3086.2284428892776;3093.767967402431;3102.2709663229125;3125.6561778204377;240702;242122;244357;290943;291245.5437722819;44882.429114981474;45081.774591126836;45097.54902450999;45147.54852451499;45347;46849.76575117103;47002.764986175534;47104.057918841434;52289.738551307;52835.735821320646;13919.652008699864;14026.22855742944;14114.153150810984;14132.50536231227;14178.503752368617;11360.943195283971;11438.656840294805;11448.771024579628;11460.94269528647;11479.426028698532;13578.117422367515;13589.592312230649;13612.523561675294;13627.659308517168;13673.316334183251;6051.969740151271;6087.573869829158;6096.359882212392;6111.8472038198515;6118.388161183846;880.8722735203488;987.9950600247097;1013.002192840052;1030.525958059286;1045.4929359260752;23136.305910822703;23813.52372952566;23835.352106339444;23853.284401467983;23950.64074038891;1032.4166845732138;1088.4176965323672;1090.5855774805523;1120.1039168665036;1132.3206076354286;11893.524259029715;12202.633920982385;12214.938925305494;12274.32491212974;12277.38613069343;289373;293723;294074;294231;331248;51669.96660066777;52105;52117.61235519679;52148;52351.73824130855;1470.5735336752437;1505.2097648734502;1510.9848901510845;1512.2438780609696;1514.2731488885397;27790.94299626999;28043.298917527223;28644.713552864203;28667.856660716563;28780.568291476062;36023;36155.81922090373;36193;36215.45676814902;36241.818790905876;282617;288075;288767;289727.10272897;290330.77353811223;32835.67164328326;32850.50724239091;32860.849870254424;33167.66832331646;33189;43328.78335608302;43478;43492.13015739731;43562;43618.47335343248;27613.171604851887;28680.569791453563;29892.252693682833;30062.549061763653;30317.69682303149;29464.558031629113;29861;29994.850025749733;30277.545836812908;31200.375992480476;35300.646993529735;35694.4645830318;35757;35865.82067089648;35884.820575896956;23429.531409371375;29097.563536547394;30031.84984075153;30436.239094022825;30561;22608.547829043655;22801.97391117437;23471;23715;24615.50768984646 19:06:24 INFO - PID 1654 | |2;jquery.html;408.8364654138342;417.80572034004183;422.52043930139286;434.19023521133084;451.32076225280946;20699.275525356614;21356.679649805264;21495.140194392225;22308.330750077504;22391.664125038136;23877.52244955104;24386.512269754596;24396.78016099197;24412.77936103192;24564.263072107864;6126.87746245076;6282.371762823726;6286.019903730333;6288.968555157241;6290.056491526262;6196.504279657616;6218.844528886769;6272.780452684134;6273.749050037993;6278.309385967563;6160.6611636359985;6556.27880933097;6830.668019736154;6980.616066116361;6992.580445173306;6943.7569685061035;7001.684924178424;7006.929930700679;7017.2982701729925;7027.964860175692;6199.132121502993;6518.543701940866;6818.386345228953;6879.346462086094;6881.827954301132;25336.619950700755;30299.848500757354;31041.689583104333;31459.32595729366;31530.684693153235;2305.2738387407912;2350.048230466664;2352.0826877517784;2352.482453860148;2353.882305884716;18548.721769173473;19037.61924761497;19159.904200479046;19175.80824191754;19209.80790192094;2002.6094911492264;2244.55108978205;2343.238447504568;2352.8117750579913;2372.1223147435385;141916;142981.1403771929;147365.2631736845;192317;198775.0122498771;24127.758722412553;25088;25296.494070118493;26040;26615.733842661713;42243.57756422397;42263.943401415214;43182;43219.56780432155;43308.56691433108;11031.338119712844;11164.497597607964;11246.943765281121;11271.154663400113;11318.603848865416;8788.736337909873;8795.956020219986;8807.251383632334;8881.73354799357;8923.330750193762;11803.763924721457;11999.460024298927;12048.87951120495;12098.213616114925;12146.271223726428;4131.814068366931;4751.192297309466;4849.806007759721;4872;4894.437139728977;964.3297907953905;977.2524019125375;984.247051005981;1008.3798463944621;1036.6941752183125;19658.935811739848;20249.493762656053;20671.379858604265;20899.268525601532;21205.575888482144;909.0727457992942;1009.8386855116528;1018.7351288664852;1041.3335465302212;1049.0506091986765;10933.343999359904;10999;11017;11151.944240278746;11196.440177991068;517993.41003295494;566378;574513;577614;579187;126846;141453;141483;142241.28879355537;142507;1163.5113252433998;1177.0818761366147;1233.1491271022965;1257.5409975359112;1260.3887114749339;19491.512712181742;20286.797132028492;21610.567788643824;21829.235976740736;23083.307500775005;53588;66552.3344766546;67128;67934;70131.64934175297;80633.38733225185;80942.59528702318;81602;81857.59071204606;82098.17901820905;9983.650572230228;10150.695479135638;10227.897721022991;10260.743481412726;10265.897341026493;17055.744163837306;17645.823541764417;17743.55641108931;17825.46523604294;17860.55348616295;12634.810477842655;14203.715925681636;14460.493882714476;14590.416383344547;14633.634159146106;12918.351737893898;13038.674033149247;13041.282729449971;13105.737885242432;13171.736565268833;14533.78199327032;14692.853071469148;14855.92572037133;14861.554153375417;14866.776998345249;10070.748231294276;10185.287029907835;10185.439800811026;10196.745081373025;10211.693649190536;14133.646658833612;14268.357923893242;14370.640733981316;14404.42382304696;14411.639709007359 19:06:24 INFO - PID 1654 | |3;mootools.html;1262.829518015068;1391.8217988847452;1398.196037278565;1404.4101477379493;1404.6628809085823;11540.653780386581;11690.766184676302;11991.220570662912;12163.209391389564;12268.754624907497;11982.101342399334;12012;12145.392730363468;12146.453409596566;12177.269363838153;2536.987315063422;2586.7283935186815;2587.4954383895147;2587.508373409056;2590.339463436821;2500.062476571286;2532.227670560478;2548.923532294025;2549.337172335197;2551.8979240830345;2517.1567524879188;2529.0895277700015;2544.4529426173367;2549.337172335197;2551.2473820223076;2501.537215615117;2506.0602274147195;2517.257409064321;2526.519961207383;2533.911313104051;2454.3864033991504;2501.2996361018945;2510.694438891784;2561.513312470643;2562.0392352867675;11790.764184716258;11804.350760708157;11831.585894493737;11832.290062596272;11843.940780296129;6432.195975503062;6592.439642630382;6685.632290224035;6703.698333575001;6705.564138331041;4123.711340206187;4312.094460163369;4315.43899293093;4382.978085109586;4410.1179764047;1248.8001919692897;1272.0840994483956;1272.306592906868;1298.2340419152745;1303.113882559859;15587.92206038974;15928.681426371639;16509.752353714703;16639.33442662292;16658.250378732864;4717.339572459859;4852.708837469763;5578.2748242728185;5802.071668533014;5804.2744656917885;11935;12132.696682583006;12133.514659413524;12149.939250303869;12178.81731774024;6342.334054924258;6438.227412710505;6531.8367040823405;6538.771143009972;6539.574927629784;5513.393526712139;6024.819255422344;6076.149339092531;6124.693765311717;6159.260888693386;3780.8298626561873;4363.2800587903275;4419.668524860584;4479.7984090716;4511.842085526991;1287.665207046156;1311.2787966618512;1317.1833463252844;1322.8081928120369;1325.516186591887;734.0237484146073;781.1056340490107;794.7456813819522;798.9640466179036;802.1417083720363;6128.938710612926;6211.782587609412;6249.187605611241;6362.6500542469685;6363.713632886531;574.1559906936797;587.9882402351928;601.6059481039925;606.5663050918614;618.811262564922;3204.6955539223736;3827.6363745444137;3872.419137129397;3879.650831425185;3938.606139386096;12554.937225313997;14639.268036598127;15145.924270378799;15156.696866062617;15203;15014.174220417983;15199.708024817794;15212.239388030554;15227.467038653594;15242.161681107424;8043.959780201061;8486.618102185417;8549.957250213834;8700.869486957701;8741.256993155526;5784.595078344644;6178.567500274938;6786.016027676058;6871;7166.032585600877;13592.79610805819;13607.319634018457;13650.93174534121;13666.590002299947;13880.51418200358;13532.323383830571;14075.225862577656;14128.43486260538;14167.079139855876;14203.289835507976;9376.531173441437;9591.18474929611;9651.083147101013;9657.758556035875;9672.564734587102;10544.841827372442;10600.681979540626;10679.19906007037;10680.145588353069;10780.975807298295;9117.680881169128;9162.633494660138;9165.220956218529;9168.862467062865;9227.815443691223;8969;8978.506182160045;9018.954905225432;9082.818343632958;9165;6921.515493915377;8060.677572897019;8236.34109271245;8254.380921431037;8269.421140520346;5924.940750592439;5967.194428752063;6083.574149809471;6099.878002440015;6171.105189747372;5981.910271345846;6004.729787159501;6202.689865506616;6242.500599952084;6338.65137417428 19:06:24 INFO - PID 1654 | |4;prototype.html;391.7213717745072;393.3823896482522;409.9959000409997;410.9137081212945;421.7827818673382;5656.547476201894;5855.385184555625;6395.872082558346;6599.6370199639005;6666.4000239978395;4721.268203428465;4837.3227748115205;6317.810465686037;6471.223453185625;6496.382843629859;3948;4705.6236600516695;4710.552497512731;4716.716996980193;4948.901021979558;4527.705699129546;4682.274247491644;4702.153612349775;4728.574428301452;4746.90506189876;4276.016516201275;4280.4221430107;4659.790309436084;4667.18324293249;4741.8103275868925;4665.88335291617;4667.906641867161;4689.788959496831;4696.835610753641;4709.552592503706;4018.9397159042637;4027.275090483741;4037.858674946392;4039.5758445363404;4046.9477935736627;4765.976170119162;5322.467753224685;5592.7762889484375;5767.423257674241;6394.872102557969;4660.5339466053465;5316.122839731478;5532.1425179097305;5549.889002219973;5553.9175757454595;6854.588724676535;6866.969954506814;6871.828204294932;6919.342662447059;6941.68762405693;3840.9039774005596;4533.801106463363;4552.840650577247;4560.856397493173;4566.337881007235;6993.125859267591;7057.894131587979;7161.104861892263;7263.912837044235;7375.18872923978;4581.48723507345;4616.538346165356;4622.976885115553;4624.312126073959;4624.9768751156025;4689.718616882998;4703.153432382204;4713.8350157745;4714.528547145258;4728.361671174416;4599.356090147382;4663.300504924288;5489.945100549024;5502.2296878437055;5537.916931246034;6414.839629009219;6421.5825971311715;6930.13373328334;6939.861202776017;6940.826479338057;1542.8071491063617;1609.967800644004;1611.1219385434965;1622.4564770801915;1628.7719719239317;893.4996402014892;913.871368859458;948.5826236456076;1037.99481002596;1080.6163811846839;6031.306399763996;6509.372656835791;6544.345565443513;6575;6576.967115164393;6064.636121832704;7084.504084714124;7141.642917854087;7168.892466613005;7187.676554555058;5556.305461817273;5594.384617692051;6088.695565221809;6484.448821850101;6507.8698426031215;3559.2347645255877;3759.9060023499633;3775.1505911169766;3798.164403831153;3810.2760475509563;7426.5915374654915;7432.8073218400705;7437.479376443705;7463.9626801866725;7475.813104672318;6289.685515724195;6731.730730770812;6801;7354.823228283556;7416.7774966751085;6791.58898591003;7413.962930185315;7429.517081389692;7447.776566703116;7448.553086814808;7164.134346261537;7170.960210769514;7203.099612548432;7205.423566114593;7230.602316872622;4939;5680.772769089355;5997.6701281428195;6113.746681930104;6968.965155174192;6448.484121270381;6539.70571324282;7192.849144136913;7366.521176123535;7458.627068646438;4490.977545112253;4584.372547745503;4629.236176031052;4648.628109751144;4714.528547145395;6730.4952128589575;7149.857002860018;7235.2041275458605;7243.949627303906;7246.239144889711;6186.690665466619;6990.545614535039;6992.510524263253;7001.719931202899;7053.365197132282;5636.323641163087;6922.10012698346;6965.303469652994;6965.860682786417;6968.477364197604;5443.8094666686475;5716.570857285678;6786.76246331376;6811.863762724817;6928.856738638068;5433.945660543344;5682.886342273048;6269.49844012487;6843.657817109224;7058.364747172779;5330.600204984564;6051.881841355666;6712.127423435118;6742.359475849787;6771.356721111487 19:06:24 INFO - PID 1654 | |5;yui.html;743.6281859070465;815.5514467043129;816.1797393619419;852.699633059584;863.171355498721;16705.83294167058;17115.657686846273;17129.914350428262;17165.65668686627;17172.883762555426;15360.769588456182;15852.048877067384;15893.966892152032;15951.760723589156;16088.195590220472;981.6220755009338;1004.0511716427978;1005.8792944846592;1009.3187098708379;1013.1287093099928;1035.3322107240824;1037.9169666426706;1038.2420832792066;1038.7455073507;1039.3763741754963;1033.1837848099992;1082.6156714366405;1087.5704096881727;1090.1987039525943;1098.5825386353174;1019.8419245016997;1081.7079388565082;1081.8431327457474;1082.3938594387168;1108.6064447121316;797.5294576200012;799.4883274704194;800.7637746864689;801.1427772283658;801.5711594297023;16748.41380551687;16834.83165168345;16959.491215263562;17158.742618860728;17170.742438863428;17604.735928961076;17756.20097095634;17825.821741782675;18118.99999999987;18201.544961375937;14310.785338219936;14541.70916581673;14549.78175327371;14552.490662826856;14564.417423303055;3584.265225628766;3631.618680038611;3650.7261955353456;3653.4702468142236;3654.634536546351;139061;141425.58574414227;142261.28869355586;142701;143551.99999999895;11335.376554289593;13680.863191368158;13808.378622961991;13920.443182272596;13953.372098255602;26359.34101647436;26809.597856032175;26901.865490672422;27008;27025.86487067552;7558.13081495624;7699.884501732478;7712.730054448068;7713.421493387906;7732.9226707732205;3932.724709270381;4021.195760847842;4021.4973128358956;4027.134166154291;4027.295223335963;4346.587074227943;4434.733915965053;4704.482506924235;4829.444613869379;4915.992221594564;881.6473410635668;928.5914197752967;975.9609615615437;977.0961860279214;994.0208894239169;565.5362602665857;633.6926590603554;665.5474277491255;670.2827974067798;676.6041865508635;9614;10506.106980906556;12415.751684966432;12492.188007779465;12732.490700372067;622.6712096758092;651.7555916531301;664.4883439751461;672.2605134352174;682.6654939079815;4837.564619184291;5660;5715.399883012307;5883.117532370179;6050.0017497112485;123550.38224808818;144910;145692;149215;151359.86401359775;107195;131169.5064394846;131880;131958.34020829835;131972;106321.46839265754;106828.46585767176;107274;108072.45963770131;109136;11339.489722962651;11591.76816463683;11600.361980090838;11655.708607284885;11746.471408786796;45041;45999.85000375017;47480.2877956824;47811.56565303046;48070.5192948066;90985.54507227421;93408;96059;96713.51643241738;101516.49241753743;17599.384021559184;18301;18457.81542184561;18478.815211848247;18489.81510184881;18741;19024.76578981604;19600;19709.901450492656;20188.39434816958;17458.563535911195;18203.635927281117;18413.26346946107;18697.065146742338;18807.623847523246;17805.376811811526;17862.46412607624;18712.438626841216;18747.250109995446;18846.246150154388;14842.703145936805;15012.174330411932;15145.697086058437;15490.07059576429;18200;12048.277103373826;12269.263844169378;12415.62753117408;12449.93775031119;12510.87489125097;11885.5840045598;14050.086744361584;14259.144451332953;14364.640883977983;14770.855248794185 19:06:24 INFO - PID 1654 | __end_tp_report 19:06:24 INFO - PID 1654 | __start_cc_report 19:06:24 INFO - PID 1654 | _x_x_mozilla_cycle_collect,453 19:06:24 INFO - PID 1654 | __end_cc_report 19:06:24 INFO - PID 1654 | __startTimestamp1496282784054__endTimestamp 19:06:24 INFO - PID 1654 | 19:06:24 INFO - PID 1654 | ------- Summary: start ------- 19:06:24 INFO - PID 1654 | Number of tests: 6 19:06:24 INFO - PID 1654 | 19:06:24 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6137.90 Median:6354.00 stddev:755.07 (11.9%) stddev-sans-first:730.07 19:06:24 INFO - PID 1654 | Values: 3572.5 3575.2 3622.6 3626.8 3643.1 4554.8 4838.8 5469.3 6163.3 6240.4 6190.0 6200.9 6223.7 6231.7 6236.6 5418.8 5465.7 5481.3 5547.2 5599.4 4939.0 5398.3 5584.7 5585.4 5636.3 5406.1 5497.2 5520.9 5582.4 5590.8 4568.8 5388.3 5429.5 5441.3 5475.2 4540.5 5487.6 5494.8 5496.0 5540.1 6165.4 6254.3 6255.7 6264.3 6287.5 6523.6 6613.8 6616.0 6697.8 6702.8 6838.0 6841.8 6842.8 6842.8 6852.8 6292.6 6297.1 6309.2 6316.6 6319.4 6803.3 6874.4 6884.8 7010.5 7019.9 6256.4 6582.0 6620.3 6630.0 6653.5 5686.9 6669.7 6694.7 6728.5 6834.3 6632.5 6650.6 6666.5 6668.5 6684.1 6663.1 6665.3 6675.7 6686.4 6690.2 5234.1 5293.5 5307.9 5315.9 5350.5 4802.0 4809.3 4815.4 4856.2 4870.6 6237.5 6330.4 6349.2 6354.0 6367.7 6632.6 6697.2 6737.2 6823.2 6852.7 6247.1 6316.1 6320.6 6326.2 6345.0 5935.3 5953.9 5973.0 5973.1 5983.3 5655.9 6823.6 6929.4 7001.5 7003.0 5593.6 6723.6 6807.4 6823.6 6831.7 6183.6 6552.1 6935.6 6947.1 6980.3 6474.8 6584.1 6646.1 6660.0 6674.0 6664.0 6668.4 6762.3 6770.4 6798.0 6547.3 6549.6 6550.9 6562.5 6580.3 6406.4 6479.3 6501.0 6540.7 6571.8 5452.0 6470.0 6475.6 6495.7 6517.5 6612.9 6621.9 6623.6 6644.5 6649.3 5345.5 6255.1 6297.0 6388.8 6437.6 19:06:24 INFO - PID 1654 | 19:06:24 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:43266.59 Median:32855.68 stddev:74452.48 (226.6%) stddev-sans-first:74592.51 19:06:24 INFO - PID 1654 | Values: 631.4 751.2 797.5 1247.9 1278.9 37145.4 37604.9 37618.2 37627.8 37811.0 37288.8 37605.1 37606.4 37630.4 37719.5 7655.2 7811.9 7968.8 8165.3 8169.1 8158.7 8231.8 8242.6 8259.3 8263.2 8226.2 8250.8 8258.5 8279.0 8279.7 8168.5 8230.6 8232.6 8239.1 8259.5 7982.6 8017.0 8039.7 8046.9 8057.8 35250.0 35323.3 35419.0 35525.3 35526.8 3531.5 3560.8 3562.3 3570.2 3578.9 28746.7 29018.6 29051.4 29054.0 29141.3 3024.2 3086.2 3093.8 3102.3 3125.7 240702.0 242122.0 244357.0 290943.0 291245.5 44882.4 45081.8 45097.5 45147.5 45347.0 46849.8 47002.8 47104.1 52289.7 52835.7 13919.7 14026.2 14114.2 14132.5 14178.5 11360.9 11438.7 11448.8 11460.9 11479.4 13578.1 13589.6 13612.5 13627.7 13673.3 6052.0 6087.6 6096.4 6111.8 6118.4 880.9 988.0 1013.0 1030.5 1045.5 23136.3 23813.5 23835.4 23853.3 23950.6 1032.4 1088.4 1090.6 1120.1 1132.3 11893.5 12202.6 12214.9 12274.3 12277.4 289373.0 293723.0 294074.0 294231.0 331248.0 51670.0 52105.0 52117.6 52148.0 52351.7 1470.6 1505.2 1511.0 1512.2 1514.3 27790.9 28043.3 28644.7 28667.9 28780.6 36023.0 36155.8 36193.0 36215.5 36241.8 282617.0 288075.0 288767.0 289727.1 290330.8 32835.7 32850.5 32860.8 33167.7 33189.0 43328.8 43478.0 43492.1 43562.0 43618.5 27613.2 28680.6 29892.3 30062.5 30317.7 29464.6 29861.0 29994.9 30277.5 31200.4 35300.6 35694.5 35757.0 35865.8 35884.8 23429.5 29097.6 30031.8 30436.2 30561.0 22608.5 22802.0 23471.0 23715.0 24615.5 19:06:24 INFO - PID 1654 | 19:06:24 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:38927.08 Median:2274.91 stddev:95836.30 (4212.7%) stddev-sans-first:96061.51 19:06:24 INFO - PID 1654 | Values: 408.8 417.8 422.5 434.2 451.3 20699.3 21356.7 21495.1 22308.3 22391.7 23877.5 24386.5 24396.8 24412.8 24564.3 6126.9 6282.4 6286.0 6289.0 6290.1 6196.5 6218.8 6272.8 6273.7 6278.3 6160.7 6556.3 6830.7 6980.6 6992.6 6943.8 7001.7 7006.9 7017.3 7028.0 6199.1 6518.5 6818.4 6879.3 6881.8 25336.6 30299.8 31041.7 31459.3 31530.7 2305.3 2350.0 2352.1 2352.5 2353.9 18548.7 19037.6 19159.9 19175.8 19209.8 2002.6 2244.6 2343.2 2352.8 2372.1 141916.0 142981.1 147365.3 192317.0 198775.0 24127.8 25088.0 25296.5 26040.0 26615.7 42243.6 42263.9 43182.0 43219.6 43308.6 11031.3 11164.5 11246.9 11271.2 11318.6 8788.7 8796.0 8807.3 8881.7 8923.3 11803.8 11999.5 12048.9 12098.2 12146.3 4131.8 4751.2 4849.8 4872.0 4894.4 964.3 977.3 984.2 1008.4 1036.7 19658.9 20249.5 20671.4 20899.3 21205.6 909.1 1009.8 1018.7 1041.3 1049.1 10933.3 10999.0 11017.0 11151.9 11196.4 517993.4 566378.0 574513.0 577614.0 579187.0 126846.0 141453.0 141483.0 142241.3 142507.0 1163.5 1177.1 1233.1 1257.5 1260.4 19491.5 20286.8 21610.6 21829.2 23083.3 53588.0 66552.3 67128.0 67934.0 70131.6 80633.4 80942.6 81602.0 81857.6 82098.2 9983.7 10150.7 10227.9 10260.7 10265.9 17055.7 17645.8 17743.6 17825.5 17860.6 12634.8 14203.7 14460.5 14590.4 14633.6 12918.4 13038.7 13041.3 13105.7 13171.7 14533.8 14692.9 14855.9 14861.6 14866.8 10070.7 10185.3 10185.4 10196.7 10211.7 14133.6 14268.4 14370.6 14404.4 14411.6 19:06:24 INFO - PID 1654 | 19:06:24 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:7122.20 Median:3492.76 stddev:4560.87 (130.6%) stddev-sans-first:4552.41 19:06:24 INFO - PID 1654 | Values: 1262.8 1391.8 1398.2 1404.4 1404.7 11540.7 11690.8 11991.2 12163.2 12268.8 11982.1 12012.0 12145.4 12146.5 12177.3 2537.0 2586.7 2587.5 2587.5 2590.3 2500.1 2532.2 2548.9 2549.3 2551.9 2517.2 2529.1 2544.5 2549.3 2551.2 2501.5 2506.1 2517.3 2526.5 2533.9 2454.4 2501.3 2510.7 2561.5 2562.0 11790.8 11804.4 11831.6 11832.3 11843.9 6432.2 6592.4 6685.6 6703.7 6705.6 4123.7 4312.1 4315.4 4383.0 4410.1 1248.8 1272.1 1272.3 1298.2 1303.1 15587.9 15928.7 16509.8 16639.3 16658.3 4717.3 4852.7 5578.3 5802.1 5804.3 11935.0 12132.7 12133.5 12149.9 12178.8 6342.3 6438.2 6531.8 6538.8 6539.6 5513.4 6024.8 6076.1 6124.7 6159.3 3780.8 4363.3 4419.7 4479.8 4511.8 1287.7 1311.3 1317.2 1322.8 1325.5 734.0 781.1 794.7 799.0 802.1 6128.9 6211.8 6249.2 6362.7 6363.7 574.2 588.0 601.6 606.6 618.8 3204.7 3827.6 3872.4 3879.7 3938.6 12554.9 14639.3 15145.9 15156.7 15203.0 15014.2 15199.7 15212.2 15227.5 15242.2 8044.0 8486.6 8550.0 8700.9 8741.3 5784.6 6178.6 6786.0 6871.0 7166.0 13592.8 13607.3 13650.9 13666.6 13880.5 13532.3 14075.2 14128.4 14167.1 14203.3 9376.5 9591.2 9651.1 9657.8 9672.6 10544.8 10600.7 10679.2 10680.1 10781.0 9117.7 9162.6 9165.2 9168.9 9227.8 8969.0 8978.5 9019.0 9082.8 9165.0 6921.5 8060.7 8236.3 8254.4 8269.4 5924.9 5967.2 6083.6 6099.9 6171.1 5981.9 6004.7 6202.7 6242.5 6338.7 19:06:24 INFO - PID 1654 | 19:06:24 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5479.21 Median:6014.49 stddev:1755.33 (29.2%) stddev-sans-first:1718.22 19:06:24 INFO - PID 1654 | Values: 391.7 393.4 410.0 410.9 421.8 5656.5 5855.4 6395.9 6599.6 6666.4 4721.3 4837.3 6317.8 6471.2 6496.4 3948.0 4705.6 4710.6 4716.7 4948.9 4527.7 4682.3 4702.2 4728.6 4746.9 4276.0 4280.4 4659.8 4667.2 4741.8 4665.9 4667.9 4689.8 4696.8 4709.6 4018.9 4027.3 4037.9 4039.6 4046.9 4766.0 5322.5 5592.8 5767.4 6394.9 4660.5 5316.1 5532.1 5549.9 5553.9 6854.6 6867.0 6871.8 6919.3 6941.7 3840.9 4533.8 4552.8 4560.9 4566.3 6993.1 7057.9 7161.1 7263.9 7375.2 4581.5 4616.5 4623.0 4624.3 4625.0 4689.7 4703.2 4713.8 4714.5 4728.4 4599.4 4663.3 5489.9 5502.2 5537.9 6414.8 6421.6 6930.1 6939.9 6940.8 1542.8 1610.0 1611.1 1622.5 1628.8 893.5 913.9 948.6 1038.0 1080.6 6031.3 6509.4 6544.3 6575.0 6577.0 6064.6 7084.5 7141.6 7168.9 7187.7 5556.3 5594.4 6088.7 6484.4 6507.9 3559.2 3759.9 3775.2 3798.2 3810.3 7426.6 7432.8 7437.5 7464.0 7475.8 6289.7 6731.7 6801.0 7354.8 7416.8 6791.6 7414.0 7429.5 7447.8 7448.6 7164.1 7171.0 7203.1 7205.4 7230.6 4939.0 5680.8 5997.7 6113.7 6969.0 6448.5 6539.7 7192.8 7366.5 7458.6 4491.0 4584.4 4629.2 4648.6 4714.5 6730.5 7149.9 7235.2 7243.9 7246.2 6186.7 6990.5 6992.5 7001.7 7053.4 5636.3 6922.1 6965.3 6965.9 6968.5 5443.8 5716.6 6786.8 6811.9 6928.9 5433.9 5682.9 6269.5 6843.7 7058.4 5330.6 6051.9 6712.1 6742.4 6771.4 19:06:24 INFO - PID 1654 | 19:06:24 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:26592.43 Median:17680.47 stddev:40585.02 (229.5%) stddev-sans-first:40652.46 19:06:24 INFO - PID 1654 | Values: 743.6 815.6 816.2 852.7 863.2 16705.8 17115.7 17129.9 17165.7 17172.9 15360.8 15852.0 15894.0 15951.8 16088.2 981.6 1004.1 1005.9 1009.3 1013.1 1035.3 1037.9 1038.2 1038.7 1039.4 1033.2 1082.6 1087.6 1090.2 1098.6 1019.8 1081.7 1081.8 1082.4 1108.6 797.5 799.5 800.8 801.1 801.6 16748.4 16834.8 16959.5 17158.7 17170.7 17604.7 17756.2 17825.8 18119.0 18201.5 14310.8 14541.7 14549.8 14552.5 14564.4 3584.3 3631.6 3650.7 3653.5 3654.6 139061.0 141425.6 142261.3 142701.0 143552.0 11335.4 13680.9 13808.4 13920.4 13953.4 26359.3 26809.6 26901.9 27008.0 27025.9 7558.1 7699.9 7712.7 7713.4 7732.9 3932.7 4021.2 4021.5 4027.1 4027.3 4346.6 4434.7 4704.5 4829.4 4916.0 881.6 928.6 976.0 977.1 994.0 565.5 633.7 665.5 670.3 676.6 9614.0 10506.1 12415.8 12492.2 12732.5 622.7 651.8 664.5 672.3 682.7 4837.6 5660.0 5715.4 5883.1 6050.0 123550.4 144910.0 145692.0 149215.0 151359.9 107195.0 131169.5 131880.0 131958.3 131972.0 106321.5 106828.5 107274.0 108072.5 109136.0 11339.5 11591.8 11600.4 11655.7 11746.5 45041.0 45999.9 47480.3 47811.6 48070.5 90985.5 93408.0 96059.0 96713.5 101516.5 17599.4 18301.0 18457.8 18478.8 18489.8 18741.0 19024.8 19600.0 19709.9 20188.4 17458.6 18203.6 18413.3 18697.1 18807.6 17805.4 17862.5 18712.4 18747.3 18846.2 14842.7 15012.2 15145.7 15490.1 18200.0 12048.3 12269.3 12415.6 12449.9 12510.9 11885.6 14050.1 14259.1 14364.6 14770.9 19:06:24 INFO - PID 1654 | -------- Summary: end -------- 19:06:24 INFO - PID 1654 | 19:06:24 INFO - TEST-INFO | 1654: exit 0 19:06:24 INFO - TEST-OK | dromaeo_css | took 1143454ms 19:06:24 INFO - TEST-START | kraken 19:06:24 INFO - operating with platform_type : mac_ 19:06:24 INFO - Initialising browser for kraken test... 19:06:24 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpKCWX8n/profile 19:06:24 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 19:06:58 INFO - TEST-INFO | 1663: killed by SIGTERM 19:06:58 INFO - Browser initialized. 19:06:58 INFO - Running cycle 1/1 for kraken test... 19:06:58 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpKCWX8n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:07:00 INFO - PID 1667 | 2017-05-31 19:07:00.884 plugin-container[1668:11588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 19:07:00 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:07:00 INFO - PID 1667 | 2017-05-31 19:07:00.884 plugin-container[1668:11588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 19:07:00 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:07:00 INFO - PID 1667 | 2017-05-31 19:07:00.884 plugin-container[1668:11588] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:07:01 INFO - PID 1667 | 2017-05-31 19:07:01.308 plugin-container[1669:11638] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 19:07:01 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:07:01 INFO - PID 1667 | 2017-05-31 19:07:01.309 plugin-container[1669:11638] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 19:07:01 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:07:01 INFO - PID 1667 | 2017-05-31 19:07:01.309 plugin-container[1669:11638] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:07:10 INFO - PID 1667 | RSS: Main: 268910592 19:07:10 INFO - PID 1667 | 19:07:13 INFO - PID 1667 | 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) 19:07:14 INFO - PID 1667 | RSS: Main: 276832256 19:07:14 INFO - PID 1667 | 19:07:16 INFO - PID 1667 | 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) 19:07:17 INFO - PID 1667 | RSS: Main: 284450816 19:07:17 INFO - PID 1667 | 19:07:21 INFO - PID 1667 | 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) 19:07:21 INFO - PID 1667 | RSS: Main: 282370048 19:07:21 INFO - PID 1667 | 19:07:23 INFO - PID 1667 | 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) 19:07:24 INFO - PID 1667 | RSS: Main: 283262976 19:07:24 INFO - PID 1667 | 19:07:26 INFO - PID 1667 | 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) 19:07:26 INFO - PID 1667 | RSS: Main: 285585408 19:07:26 INFO - PID 1667 | 19:07:29 INFO - PID 1667 | 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) 19:07:30 INFO - PID 1667 | RSS: Main: 289722368 19:07:30 INFO - PID 1667 | 19:07:33 INFO - PID 1667 | 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) 19:07:34 INFO - PID 1667 | RSS: Main: 292696064 19:07:34 INFO - PID 1667 | 19:07:36 INFO - PID 1667 | 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) 19:07:37 INFO - PID 1667 | RSS: Main: 291323904 19:07:37 INFO - PID 1667 | 19:07:39 INFO - PID 1667 | 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) 19:07:39 INFO - PID 1667 | RSS: Main: 290885632 19:07:39 INFO - PID 1667 | 19:07:41 INFO - PID 1667 | 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) 19:07:42 INFO - PID 1667 | RSS: Main: 290742272 19:07:42 INFO - PID 1667 | 19:07:44 INFO - PID 1667 | 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) 19:07:45 INFO - PID 1667 | RSS: Main: 290803712 19:07:45 INFO - PID 1667 | 19:07:48 INFO - PID 1667 | 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) 19:07:48 INFO - PID 1667 | RSS: Main: 290627584 19:07:48 INFO - PID 1667 | 19:07:52 INFO - PID 1667 | 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) 19:07:52 INFO - PID 1667 | RSS: Main: 290627584 19:07:52 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | 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) 19:07:54 INFO - PID 1667 | RSS: Main: 292560896 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | __start_tp_report 19:07:54 INFO - PID 1667 | _x_x_mozilla_page_load 19:07:54 INFO - PID 1667 | _x_x_mozilla_page_load_details 19:07:54 INFO - PID 1667 | |i|pagename|runs| 19:07:54 INFO - PID 1667 | |0;ai-astar;102;116;114;116;116;114;121;114;115;115 19:07:54 INFO - PID 1667 | |1;audio-beat-detection;142;112;108;151;111;110;111;115;138;111 19:07:54 INFO - PID 1667 | |2;audio-dft;273;272;198;201;241;247;237;260;287;202 19:07:54 INFO - PID 1667 | |3;audio-fft;81;58;57;88;48;63;72;83;93;85 19:07:54 INFO - PID 1667 | |4;audio-oscillator;78;69;88;72;69;68;90;72;71;93 19:07:54 INFO - PID 1667 | |5;imaging-gaussian-blur;89;90;88;89;89;89;89;99;112;135 19:07:54 INFO - PID 1667 | |6;imaging-darkroom;95;96;97;95;99;96;96;96;99;96 19:07:54 INFO - PID 1667 | |7;imaging-desaturate;92;98;143;94;92;92;95;89;92;90 19:07:54 INFO - PID 1667 | |8;json-parse-financial;51;52;50;52;52;51;51;49;52;52 19:07:54 INFO - PID 1667 | |9;json-stringify-tinderbox;59;67;89;83;89;60;56;58;57;58 19:07:54 INFO - PID 1667 | |10;stanford-crypto-aes;92;99;73;113;114;112;111;90;122;88 19:07:54 INFO - PID 1667 | |11;stanford-crypto-ccm;140;152;137;140;136;140;148;155;132;147 19:07:54 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;183;194;226;239;187;184;182;186;181;186 19:07:54 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;65;67;74;95;91;88;61;70;93;96 19:07:54 INFO - PID 1667 | __end_tp_report 19:07:54 INFO - PID 1667 | __start_cc_report 19:07:54 INFO - PID 1667 | _x_x_mozilla_cycle_collect,1208 19:07:54 INFO - PID 1667 | __end_cc_report 19:07:54 INFO - PID 1667 | __startTimestamp1496282874871__endTimestamp 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | ------- Summary: start ------- 19:07:54 INFO - PID 1667 | Number of tests: 14 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.50 stddev:4.79 (4.1%) stddev-sans-first:2.18 19:07:54 INFO - PID 1667 | Values: 102.0 116.0 114.0 116.0 116.0 114.0 121.0 114.0 115.0 115.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:120.90 Median:113.50 stddev:16.11 (14.2%) stddev-sans-first:15.17 19:07:54 INFO - PID 1667 | Values: 142.0 112.0 108.0 151.0 111.0 110.0 111.0 115.0 138.0 111.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:241.80 Median:253.50 stddev:32.45 (12.8%) stddev-sans-first:32.40 19:07:54 INFO - PID 1667 | Values: 273.0 272.0 198.0 201.0 241.0 247.0 237.0 260.0 287.0 202.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:72.80 Median:82.00 stddev:15.42 (18.8%) stddev-sans-first:16.07 19:07:54 INFO - PID 1667 | Values: 81.0 58.0 57.0 88.0 48.0 63.0 72.0 83.0 93.0 85.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:75.00 stddev:9.67 (12.9%) stddev-sans-first:10.25 19:07:54 INFO - PID 1667 | Values: 78.0 69.0 88.0 72.0 69.0 68.0 90.0 72.0 71.0 93.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:89.00 stddev:15.36 (17.3%) stddev-sans-first:16.02 19:07:54 INFO - PID 1667 | Values: 89.0 90.0 88.0 89.0 89.0 89.0 89.0 99.0 112.0 135.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:96.00 stddev:1.43 (1.5%) stddev-sans-first:1.41 19:07:54 INFO - PID 1667 | Values: 95.0 96.0 97.0 95.0 99.0 96.0 96.0 96.0 99.0 96.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:92.00 stddev:16.12 (17.5%) stddev-sans-first:16.96 19:07:54 INFO - PID 1667 | Values: 92.0 98.0 143.0 94.0 92.0 92.0 95.0 89.0 92.0 90.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:1.09 19:07:54 INFO - PID 1667 | Values: 51.0 52.0 50.0 52.0 52.0 51.0 51.0 49.0 52.0 52.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:63.50 stddev:13.81 (21.7%) stddev-sans-first:14.29 19:07:54 INFO - PID 1667 | Values: 59.0 67.0 89.0 83.0 89.0 60.0 56.0 58.0 57.0 58.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:101.40 Median:80.50 stddev:15.39 (19.1%) stddev-sans-first:15.95 19:07:54 INFO - PID 1667 | Values: 92.0 99.0 73.0 113.0 114.0 112.0 111.0 90.0 122.0 88.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:143.50 stddev:7.44 (5.2%) stddev-sans-first:7.83 19:07:54 INFO - PID 1667 | Values: 140.0 152.0 137.0 140.0 136.0 140.0 148.0 155.0 132.0 147.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.80 Median:186.50 stddev:20.42 (11.0%) stddev-sans-first:21.21 19:07:54 INFO - PID 1667 | Values: 183.0 194.0 226.0 239.0 187.0 184.0 182.0 186.0 181.0 186.0 19:07:54 INFO - PID 1667 | 19:07:54 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.00 Median:89.50 stddev:13.85 (15.5%) stddev-sans-first:13.58 19:07:54 INFO - PID 1667 | Values: 65.0 67.0 74.0 95.0 91.0 88.0 61.0 70.0 93.0 96.0 19:07:54 INFO - PID 1667 | -------- Summary: end -------- 19:07:54 INFO - PID 1667 | 19:07:55 INFO - TEST-INFO | 1667: exit 0 19:07:55 INFO - TEST-OK | kraken | took 90981ms 19:07:55 INFO - SUITE-END | took 1234s 19:07:56 INFO - Completed test suite (00:20:34) 19:07:56 INFO - javascript benchmark 19:07:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8188.800995214171, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3572.481990111434, 3575.195580994276, 3622.6377362263793, 3626.818659067046, 3643.143861192621, 4554.84058057968, 4838.830640927568, 5469.261649677293, 6163.260408750951, 6240.43836054755, 6190.04054371572, 6200.937990620092, 6223.688815559227, 6231.6572588507615, 6236.594621349609, 5418.783248670048, 5465.672059676413, 5481.342238931334, 5547.1679248112705, 5599.384067752544, 4938.950610493903, 5398.2712333834825, 5584.748686309126, 5585.385607583163, 5636.323641163066, 5406.08096623573, 5497.175423686439, 5520.861978450531, 5582.413846546115, 5590.832275031756, 4568.817247310104, 5388.272583201296, 5429.53848922842, 5441.347038355423, 5475.151351540518, 4540.523245059288, 5487.6158668893195, 5494.80768173116, 5495.972520137414, 5540.141278101901, 6165.383461653843, 6254.280757712831, 6255.7184926677955, 6264.310925798159, 6287.49700023997, 6523.608583485006, 6613.768518101843, 6616.0, 6697.7655782047395, 6702.798916032478, 6837.965810170967, 6841.794746157623, 6842.794716158523, 6842.794716158523, 6852.760153394657, 6292.622442653455, 6297.149884765589, 6309.179806625109, 6316.557840951135, 6319.399657032574, 6803.251642319341, 6874.381305682413, 6884.827879302958, 7010.474214433938, 7019.89470157948, 6256.43692067707, 6582.0, 6620.271770105284, 6629.966850165815, 6653.500987425866, 5686.914696279559, 6669.666516674147, 6694.665266736643, 6728.529002969745, 6834.316568343225, 6632.535722499379, 6650.634215118215, 6666.466682665375, 6668.499862510331, 6684.131062961784, 6663.13379260693, 6665.300143484961, 6675.6662166891465, 6686.431653309426, 6690.19717633887, 5234.110201265794, 5293.523582877559, 5307.893842123136, 5315.867103322371, 5350.491703288181, 4801.95198048022, 4809.302651115569, 4815.422149342101, 4856.150173719585, 4870.561649451566, 6237.4698150657705, 6330.366963303725, 6349.238091429058, 6354.0, 6367.713452894631, 6632.6352050636715, 6697.162854643169, 6737.225219099866, 6823.215330237086, 6852.6916288767125, 6247.125402443661, 6316.084167795644, 6320.62076275425, 6326.2092238470195, 6345.016522438937, 5935.258092738408, 5953.880922381528, 5973.01445261527, 5973.093187625418, 5983.252093488314, 5655.943440565707, 6823.55646882951, 6929.41100006495, 7001.4748893834185, 7003.0, 5593.580481463825, 6723.562968407038, 6807.421369183675, 6823.556468829709, 6831.726730930707, 6183.5671502994355, 6552.148220731275, 6935.583864968118, 6947.131608548932, 6980.301969803182, 6474.838129046811, 6584.144061272004, 6646.1027761253545, 6660.0, 6673.965535342029, 6664.0, 6668.399844014062, 6762.256151823197, 6770.356816102462, 6798.0, 6547.345265473415, 6549.639769812706, 6550.901736473861, 6562.475001999924, 6580.309067547839, 6406.359364063743, 6479.287278399278, 6501.0, 6540.738370465128, 6571.835704107436, 5452.0, 6470.0, 6475.611463312216, 6495.675216239264, 6517.511186661114, 6612.933870661424, 6621.933780662324, 6623.609042101213, 6644.534882558174, 6649.268580456049, 5345.518903298566, 6255.061740738925, 6296.968515157395, 6388.8083357499345, 6437.581557198767], "value": 6089.507999556697, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.3970158498635, 751.2112282103793, 797.4896066517429, 1247.868973757755, 1278.8657190994938, 37145.44281835771, 37604.87185384439, 37618.247635047286, 37627.81186094066, 37811.0, 37288.81355593225, 37605.05987350311, 37606.4359034614, 37630.4355434668, 37719.47366316046, 7655.234476552356, 7811.882821757678, 7968.800779980489, 8165.265126138646, 8169.060558035812, 8158.673653053871, 8231.753047408558, 8242.629081691308, 8259.297959673435, 8263.173682631748, 8226.218509241613, 8250.752477425687, 8258.545779982098, 8279.0, 8279.668813247463, 8168.509889406625, 8230.588470576447, 8232.62953167109, 8239.134890836465, 8259.463134896212, 7982.560959147245, 8017.0, 8039.718609848685, 8046.919530804676, 8057.758267251992, 35250.0, 35323.29353412943, 35419.0, 35525.289494209974, 35526.75656352015, 3531.52324436203, 3560.839762210707, 3562.3231585998838, 3570.214552798406, 3578.946315805265, 28746.71253287461, 29018.564721529194, 29051.41897162066, 29053.983110591027, 29141.271468213254, 3024.2439390152463, 3086.2284428892776, 3093.767967402431, 3102.2709663229125, 3125.6561778204377, 240702.0, 242122.0, 244357.0, 290943.0, 291245.5437722819, 44882.429114981474, 45081.774591126836, 45097.54902450999, 45147.54852451499, 45347.0, 46849.76575117103, 47002.764986175534, 47104.057918841434, 52289.738551307, 52835.735821320646, 13919.652008699864, 14026.22855742944, 14114.153150810984, 14132.50536231227, 14178.503752368617, 11360.943195283971, 11438.656840294805, 11448.771024579628, 11460.94269528647, 11479.426028698532, 13578.117422367515, 13589.592312230649, 13612.523561675294, 13627.659308517168, 13673.316334183251, 6051.969740151271, 6087.573869829158, 6096.359882212392, 6111.8472038198515, 6118.388161183846, 880.8722735203488, 987.9950600247097, 1013.002192840052, 1030.525958059286, 1045.4929359260752, 23136.305910822703, 23813.52372952566, 23835.352106339444, 23853.284401467983, 23950.64074038891, 1032.4166845732138, 1088.4176965323672, 1090.5855774805523, 1120.1039168665036, 1132.3206076354286, 11893.524259029715, 12202.633920982385, 12214.938925305494, 12274.32491212974, 12277.38613069343, 289373.0, 293723.0, 294074.0, 294231.0, 331248.0, 51669.96660066777, 52105.0, 52117.61235519679, 52148.0, 52351.73824130855, 1470.5735336752437, 1505.2097648734502, 1510.9848901510845, 1512.2438780609696, 1514.2731488885397, 27790.94299626999, 28043.298917527223, 28644.713552864203, 28667.856660716563, 28780.568291476062, 36023.0, 36155.81922090373, 36193.0, 36215.45676814902, 36241.818790905876, 282617.0, 288075.0, 288767.0, 289727.10272897, 290330.77353811223, 32835.67164328326, 32850.50724239091, 32860.849870254424, 33167.66832331646, 33189.0, 43328.78335608302, 43478.0, 43492.13015739731, 43562.0, 43618.47335343248, 27613.171604851887, 28680.569791453563, 29892.252693682833, 30062.549061763653, 30317.69682303149, 29464.558031629113, 29861.0, 29994.850025749733, 30277.545836812908, 31200.375992480476, 35300.646993529735, 35694.4645830318, 35757.0, 35865.82067089648, 35884.820575896956, 23429.531409371375, 29097.563536547394, 30031.84984075153, 30436.239094022825, 30561.0, 22608.547829043655, 22801.97391117437, 23471.0, 23715.0, 24615.50768984646], "value": 17159.072183505334, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.8364654138342, 417.80572034004183, 422.52043930139286, 434.19023521133084, 451.32076225280946, 20699.275525356614, 21356.679649805264, 21495.140194392225, 22308.330750077504, 22391.664125038136, 23877.52244955104, 24386.512269754596, 24396.78016099197, 24412.77936103192, 24564.263072107864, 6126.87746245076, 6282.371762823726, 6286.019903730333, 6288.968555157241, 6290.056491526262, 6196.504279657616, 6218.844528886769, 6272.780452684134, 6273.749050037993, 6278.309385967563, 6160.6611636359985, 6556.27880933097, 6830.668019736154, 6980.616066116361, 6992.580445173306, 6943.7569685061035, 7001.684924178424, 7006.929930700679, 7017.2982701729925, 7027.964860175692, 6199.132121502993, 6518.543701940866, 6818.386345228953, 6879.346462086094, 6881.827954301132, 25336.619950700755, 30299.848500757354, 31041.689583104333, 31459.32595729366, 31530.684693153235, 2305.2738387407912, 2350.048230466664, 2352.0826877517784, 2352.482453860148, 2353.882305884716, 18548.721769173473, 19037.61924761497, 19159.904200479046, 19175.80824191754, 19209.80790192094, 2002.6094911492264, 2244.55108978205, 2343.238447504568, 2352.8117750579913, 2372.1223147435385, 141916.0, 142981.1403771929, 147365.2631736845, 192317.0, 198775.0122498771, 24127.758722412553, 25088.0, 25296.494070118493, 26040.0, 26615.733842661713, 42243.57756422397, 42263.943401415214, 43182.0, 43219.56780432155, 43308.56691433108, 11031.338119712844, 11164.497597607964, 11246.943765281121, 11271.154663400113, 11318.603848865416, 8788.736337909873, 8795.956020219986, 8807.251383632334, 8881.73354799357, 8923.330750193762, 11803.763924721457, 11999.460024298927, 12048.87951120495, 12098.213616114925, 12146.271223726428, 4131.814068366931, 4751.192297309466, 4849.806007759721, 4872.0, 4894.437139728977, 964.3297907953905, 977.2524019125375, 984.247051005981, 1008.3798463944621, 1036.6941752183125, 19658.935811739848, 20249.493762656053, 20671.379858604265, 20899.268525601532, 21205.575888482144, 909.0727457992942, 1009.8386855116528, 1018.7351288664852, 1041.3335465302212, 1049.0506091986765, 10933.343999359904, 10999.0, 11017.0, 11151.944240278746, 11196.440177991068, 517993.41003295494, 566378.0, 574513.0, 577614.0, 579187.0, 126846.0, 141453.0, 141483.0, 142241.28879355537, 142507.0, 1163.5113252433998, 1177.0818761366147, 1233.1491271022965, 1257.5409975359112, 1260.3887114749339, 19491.512712181742, 20286.797132028492, 21610.567788643824, 21829.235976740736, 23083.307500775005, 53588.0, 66552.3344766546, 67128.0, 67934.0, 70131.64934175297, 80633.38733225185, 80942.59528702318, 81602.0, 81857.59071204606, 82098.17901820905, 9983.650572230228, 10150.695479135638, 10227.897721022991, 10260.743481412726, 10265.897341026493, 17055.744163837306, 17645.823541764417, 17743.55641108931, 17825.46523604294, 17860.55348616295, 12634.810477842655, 14203.715925681636, 14460.493882714476, 14590.416383344547, 14633.634159146106, 12918.351737893898, 13038.674033149247, 13041.282729449971, 13105.737885242432, 13171.736565268833, 14533.78199327032, 14692.853071469148, 14855.92572037133, 14861.554153375417, 14866.776998345249, 10070.748231294276, 10185.287029907835, 10185.439800811026, 10196.745081373025, 10211.693649190536, 14133.646658833612, 14268.357923893242, 14370.640733981316, 14404.42382304696, 14411.639709007359], "value": 12240.703138209901, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1262.829518015068, 1391.8217988847452, 1398.196037278565, 1404.4101477379493, 1404.6628809085823, 11540.653780386581, 11690.766184676302, 11991.220570662912, 12163.209391389564, 12268.754624907497, 11982.101342399334, 12012.0, 12145.392730363468, 12146.453409596566, 12177.269363838153, 2536.987315063422, 2586.7283935186815, 2587.4954383895147, 2587.508373409056, 2590.339463436821, 2500.062476571286, 2532.227670560478, 2548.923532294025, 2549.337172335197, 2551.8979240830345, 2517.1567524879188, 2529.0895277700015, 2544.4529426173367, 2549.337172335197, 2551.2473820223076, 2501.537215615117, 2506.0602274147195, 2517.257409064321, 2526.519961207383, 2533.911313104051, 2454.3864033991504, 2501.2996361018945, 2510.694438891784, 2561.513312470643, 2562.0392352867675, 11790.764184716258, 11804.350760708157, 11831.585894493737, 11832.290062596272, 11843.940780296129, 6432.195975503062, 6592.439642630382, 6685.632290224035, 6703.698333575001, 6705.564138331041, 4123.711340206187, 4312.094460163369, 4315.43899293093, 4382.978085109586, 4410.1179764047, 1248.8001919692897, 1272.0840994483956, 1272.306592906868, 1298.2340419152745, 1303.113882559859, 15587.92206038974, 15928.681426371639, 16509.752353714703, 16639.33442662292, 16658.250378732864, 4717.339572459859, 4852.708837469763, 5578.2748242728185, 5802.071668533014, 5804.2744656917885, 11935.0, 12132.696682583006, 12133.514659413524, 12149.939250303869, 12178.81731774024, 6342.334054924258, 6438.227412710505, 6531.8367040823405, 6538.771143009972, 6539.574927629784, 5513.393526712139, 6024.819255422344, 6076.149339092531, 6124.693765311717, 6159.260888693386, 3780.8298626561873, 4363.2800587903275, 4419.668524860584, 4479.7984090716, 4511.842085526991, 1287.665207046156, 1311.2787966618512, 1317.1833463252844, 1322.8081928120369, 1325.516186591887, 734.0237484146073, 781.1056340490107, 794.7456813819522, 798.9640466179036, 802.1417083720363, 6128.938710612926, 6211.782587609412, 6249.187605611241, 6362.6500542469685, 6363.713632886531, 574.1559906936797, 587.9882402351928, 601.6059481039925, 606.5663050918614, 618.811262564922, 3204.6955539223736, 3827.6363745444137, 3872.419137129397, 3879.650831425185, 3938.606139386096, 12554.937225313997, 14639.268036598127, 15145.924270378799, 15156.696866062617, 15203.0, 15014.174220417983, 15199.708024817794, 15212.239388030554, 15227.467038653594, 15242.161681107424, 8043.959780201061, 8486.618102185417, 8549.957250213834, 8700.869486957701, 8741.256993155526, 5784.595078344644, 6178.567500274938, 6786.016027676058, 6871.0, 7166.032585600877, 13592.79610805819, 13607.319634018457, 13650.93174534121, 13666.590002299947, 13880.51418200358, 13532.323383830571, 14075.225862577656, 14128.43486260538, 14167.079139855876, 14203.289835507976, 9376.531173441437, 9591.18474929611, 9651.083147101013, 9657.758556035875, 9672.564734587102, 10544.841827372442, 10600.681979540626, 10679.19906007037, 10680.145588353069, 10780.975807298295, 9117.680881169128, 9162.633494660138, 9165.220956218529, 9168.862467062865, 9227.815443691223, 8969.0, 8978.506182160045, 9018.954905225432, 9082.818343632958, 9165.0, 6921.515493915377, 8060.677572897019, 8236.34109271245, 8254.380921431037, 8269.421140520346, 5924.940750592439, 5967.194428752063, 6083.574149809471, 6099.878002440015, 6171.105189747372, 5981.910271345846, 6004.729787159501, 6202.689865506616, 6242.500599952084, 6338.65137417428], "value": 5319.452219311276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.7213717745072, 393.3823896482522, 409.9959000409997, 410.9137081212945, 421.7827818673382, 5656.547476201894, 5855.385184555625, 6395.872082558346, 6599.6370199639005, 6666.4000239978395, 4721.268203428465, 4837.3227748115205, 6317.810465686037, 6471.223453185625, 6496.382843629859, 3948.0, 4705.6236600516695, 4710.552497512731, 4716.716996980193, 4948.901021979558, 4527.705699129546, 4682.274247491644, 4702.153612349775, 4728.574428301452, 4746.90506189876, 4276.016516201275, 4280.4221430107, 4659.790309436084, 4667.18324293249, 4741.8103275868925, 4665.88335291617, 4667.906641867161, 4689.788959496831, 4696.835610753641, 4709.552592503706, 4018.9397159042637, 4027.275090483741, 4037.858674946392, 4039.5758445363404, 4046.9477935736627, 4765.976170119162, 5322.467753224685, 5592.7762889484375, 5767.423257674241, 6394.872102557969, 4660.5339466053465, 5316.122839731478, 5532.1425179097305, 5549.889002219973, 5553.9175757454595, 6854.588724676535, 6866.969954506814, 6871.828204294932, 6919.342662447059, 6941.68762405693, 3840.9039774005596, 4533.801106463363, 4552.840650577247, 4560.856397493173, 4566.337881007235, 6993.125859267591, 7057.894131587979, 7161.104861892263, 7263.912837044235, 7375.18872923978, 4581.48723507345, 4616.538346165356, 4622.976885115553, 4624.312126073959, 4624.9768751156025, 4689.718616882998, 4703.153432382204, 4713.8350157745, 4714.528547145258, 4728.361671174416, 4599.356090147382, 4663.300504924288, 5489.945100549024, 5502.2296878437055, 5537.916931246034, 6414.839629009219, 6421.5825971311715, 6930.13373328334, 6939.861202776017, 6940.826479338057, 1542.8071491063617, 1609.967800644004, 1611.1219385434965, 1622.4564770801915, 1628.7719719239317, 893.4996402014892, 913.871368859458, 948.5826236456076, 1037.99481002596, 1080.6163811846839, 6031.306399763996, 6509.372656835791, 6544.345565443513, 6575.0, 6576.967115164393, 6064.636121832704, 7084.504084714124, 7141.642917854087, 7168.892466613005, 7187.676554555058, 5556.305461817273, 5594.384617692051, 6088.695565221809, 6484.448821850101, 6507.8698426031215, 3559.2347645255877, 3759.9060023499633, 3775.1505911169766, 3798.164403831153, 3810.2760475509563, 7426.5915374654915, 7432.8073218400705, 7437.479376443705, 7463.9626801866725, 7475.813104672318, 6289.685515724195, 6731.730730770812, 6801.0, 7354.823228283556, 7416.7774966751085, 6791.58898591003, 7413.962930185315, 7429.517081389692, 7447.776566703116, 7448.553086814808, 7164.134346261537, 7170.960210769514, 7203.099612548432, 7205.423566114593, 7230.602316872622, 4939.0, 5680.772769089355, 5997.6701281428195, 6113.746681930104, 6968.965155174192, 6448.484121270381, 6539.70571324282, 7192.849144136913, 7366.521176123535, 7458.627068646438, 4490.977545112253, 4584.372547745503, 4629.236176031052, 4648.628109751144, 4714.528547145395, 6730.4952128589575, 7149.857002860018, 7235.2041275458605, 7243.949627303906, 7246.239144889711, 6186.690665466619, 6990.545614535039, 6992.510524263253, 7001.719931202899, 7053.365197132282, 5636.323641163087, 6922.10012698346, 6965.303469652994, 6965.860682786417, 6968.477364197604, 5443.8094666686475, 5716.570857285678, 6786.76246331376, 6811.863762724817, 6928.856738638068, 5433.945660543344, 5682.886342273048, 6269.49844012487, 6843.657817109224, 7058.364747172779, 5330.600204984564, 6051.881841355666, 6712.127423435118, 6742.359475849787, 6771.356721111487], "value": 4930.859684724076, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.6281859070465, 815.5514467043129, 816.1797393619419, 852.699633059584, 863.171355498721, 16705.83294167058, 17115.657686846273, 17129.914350428262, 17165.65668686627, 17172.883762555426, 15360.769588456182, 15852.048877067384, 15893.966892152032, 15951.760723589156, 16088.195590220472, 981.6220755009338, 1004.0511716427978, 1005.8792944846592, 1009.3187098708379, 1013.1287093099928, 1035.3322107240824, 1037.9169666426706, 1038.2420832792066, 1038.7455073507, 1039.3763741754963, 1033.1837848099992, 1082.6156714366405, 1087.5704096881727, 1090.1987039525943, 1098.5825386353174, 1019.8419245016997, 1081.7079388565082, 1081.8431327457474, 1082.3938594387168, 1108.6064447121316, 797.5294576200012, 799.4883274704194, 800.7637746864689, 801.1427772283658, 801.5711594297023, 16748.41380551687, 16834.83165168345, 16959.491215263562, 17158.742618860728, 17170.742438863428, 17604.735928961076, 17756.20097095634, 17825.821741782675, 18118.99999999987, 18201.544961375937, 14310.785338219936, 14541.70916581673, 14549.78175327371, 14552.490662826856, 14564.417423303055, 3584.265225628766, 3631.618680038611, 3650.7261955353456, 3653.4702468142236, 3654.634536546351, 139061.0, 141425.58574414227, 142261.28869355586, 142701.0, 143551.99999999895, 11335.376554289593, 13680.863191368158, 13808.378622961991, 13920.443182272596, 13953.372098255602, 26359.34101647436, 26809.597856032175, 26901.865490672422, 27008.0, 27025.86487067552, 7558.13081495624, 7699.884501732478, 7712.730054448068, 7713.421493387906, 7732.9226707732205, 3932.724709270381, 4021.195760847842, 4021.4973128358956, 4027.134166154291, 4027.295223335963, 4346.587074227943, 4434.733915965053, 4704.482506924235, 4829.444613869379, 4915.992221594564, 881.6473410635668, 928.5914197752967, 975.9609615615437, 977.0961860279214, 994.0208894239169, 565.5362602665857, 633.6926590603554, 665.5474277491255, 670.2827974067798, 676.6041865508635, 9614.0, 10506.106980906556, 12415.751684966432, 12492.188007779465, 12732.490700372067, 622.6712096758092, 651.7555916531301, 664.4883439751461, 672.2605134352174, 682.6654939079815, 4837.564619184291, 5660.0, 5715.399883012307, 5883.117532370179, 6050.0017497112485, 123550.38224808818, 144910.0, 145692.0, 149215.0, 151359.86401359775, 107195.0, 131169.5064394846, 131880.0, 131958.34020829835, 131972.0, 106321.46839265754, 106828.46585767176, 107274.0, 108072.45963770131, 109136.0, 11339.489722962651, 11591.76816463683, 11600.361980090838, 11655.708607284885, 11746.471408786796, 45041.0, 45999.85000375017, 47480.2877956824, 47811.56565303046, 48070.5192948066, 90985.54507227421, 93408.0, 96059.0, 96713.51643241738, 101516.49241753743, 17599.384021559184, 18301.0, 18457.81542184561, 18478.815211848247, 18489.81510184881, 18741.0, 19024.76578981604, 19600.0, 19709.901450492656, 20188.39434816958, 17458.563535911195, 18203.635927281117, 18413.26346946107, 18697.065146742338, 18807.623847523246, 17805.376811811526, 17862.46412607624, 18712.438626841216, 18747.250109995446, 18846.246150154388, 14842.703145936805, 15012.174330411932, 15145.697086058437, 15490.07059576429, 18200.0, 12048.277103373826, 12269.263844169378, 12415.62753117408, 12449.93775031119, 12510.87489125097, 11885.5840045598, 14050.086744361584, 14259.144451332953, 14364.640883977983, 14770.855248794185], "value": 8987.068263337153, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 114.0, 116.0, 116.0, 114.0, 121.0, 114.0, 115.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 108.0, 151.0, 111.0, 110.0, 111.0, 115.0, 138.0, 111.0], "value": 120.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [273.0, 272.0, 198.0, 201.0, 241.0, 247.0, 237.0, 260.0, 287.0, 202.0], "value": 241.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 57.0, 88.0, 48.0, 63.0, 72.0, 83.0, 93.0, 85.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 69.0, 88.0, 72.0, 69.0, 68.0, 90.0, 72.0, 71.0, 93.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 89.0, 89.0, 89.0, 89.0, 99.0, 112.0, 135.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 95.0, 99.0, 96.0, 96.0, 96.0, 99.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 143.0, 94.0, 92.0, 92.0, 95.0, 89.0, 92.0, 90.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 50.0, 52.0, 52.0, 51.0, 51.0, 49.0, 52.0, 52.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 67.0, 89.0, 83.0, 89.0, 60.0, 56.0, 58.0, 57.0, 58.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 73.0, 113.0, 114.0, 112.0, 111.0, 90.0, 122.0, 88.0], "value": 101.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 152.0, 137.0, 140.0, 136.0, 140.0, 148.0, 155.0, 132.0, 147.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 194.0, 226.0, 239.0, 187.0, 184.0, 182.0, 186.0, 181.0, 186.0], "value": 194.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 74.0, 95.0, 91.0, 88.0, 61.0, 70.0, 93.0, 96.0], "value": 80.0, "unit": "score"}]}]} 19:07:56 INFO - Return code: 0 19:07:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:07:56 INFO - # TBPL SUCCESS # 19:07:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:07:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:07:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:07:56 INFO - 2017-05-31 19:07:56.190 screenresolution[1675:12016] starting screenresolution argv=screenresolution get 19:07:56 INFO - 2017-05-31 19:07:56.192 screenresolution[1675:12016] Display 0: 1600x1200x32@60 19:07:56 INFO - 2017-05-31 19:07:56.198 screenresolution[1676:12019] starting screenresolution argv=screenresolution list 19:07:56 INFO - Available Modes on Display 0 19:07:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:07:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:07:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:07:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:07:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:07:56 INFO - Intel Iris: 19:07:56 INFO - Chipset Model: Intel Iris 19:07:56 INFO - Type: GPU 19:07:56 INFO - Bus: Built-In 19:07:56 INFO - VRAM (Dynamic, Max): 1536 MB 19:07:56 INFO - Vendor: Intel (0x8086) 19:07:56 INFO - Device ID: 0x0a2e 19:07:56 INFO - Revision ID: 0x0009 19:07:56 INFO - Displays: 19:07:56 INFO - Display: 19:07:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:07:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:07:56 INFO - Display Serial Number: 1600x1200 60 19:07:56 INFO - Main Display: Yes 19:07:56 INFO - Mirror: Off 19:07:56 INFO - Online: Yes 19:07:56 INFO - Rotation: Supported 19:07:56 INFO - Return code: 0 19:07:56 INFO - Running post-action listener: _resource_record_post_action 19:07:56 INFO - [mozharness: 2017-06-01 02:07:56.434997Z] Finished run-tests step (success) 19:07:56 INFO - Running post-run listener: _resource_record_post_run 19:07:56 INFO - Total resource usage - Wall time: 1257s; CPU: 54.0%; Read bytes: 35180032; Write bytes: 574028800; Read time: 230; Write time: 1795 19:07:56 INFO - TinderboxPrint: CPU usage
53.7% 19:07:56 INFO - TinderboxPrint: I/O read bytes / time
35,180,032 / 230 19:07:56 INFO - TinderboxPrint: I/O write bytes / time
574,028,800 / 1,795 19:07:56 INFO - TinderboxPrint: CPU idle
2,330.0 (46.3%) 19:07:56 INFO - TinderboxPrint: CPU system
1,022.0 (20.3%) 19:07:56 INFO - TinderboxPrint: CPU user
1,677.8 (33.4%) 19:07:56 INFO - TinderboxPrint: Swap in / out
278,073,344 / 0 19:07:56 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231074304; Write bytes: 232159232; Read time: 16774; Write time: 482 19:07:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:07:56 INFO - run-tests - Wall time: 1236s; CPU: 54.0%; Read bytes: 34209280; Write bytes: 323404800; Read time: 218; Write time: 1260 19:07:56 INFO - Running post-run listener: _upload_blobber_files 19:07:56 INFO - Blob upload gear active. 19:07:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:07:56 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 19:07:56 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'] 19:07:56 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 19:07:57 INFO - (blobuploader) - INFO - Open directory for files ... 19:07:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:07:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:07:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:57 INFO - (blobuploader) - INFO - Done attempting. 19:07:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:07:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:07:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:58 INFO - (blobuploader) - INFO - Done attempting. 19:07:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:07:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:07:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:59 INFO - (blobuploader) - INFO - Done attempting. 19:07:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:07:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:08:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:02 INFO - (blobuploader) - INFO - Done attempting. 19:08:02 INFO - (blobuploader) - INFO - Iteration through files over. 19:08:02 INFO - Return code: 0 19:08:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:08:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:08:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf9eafe9e5aa98f15a6014261e55229e22a87135b890a7c21cb60c40eba2cc62b979926f73e3f582e521d6ff1eb792b83a9b3f7b796612e94bd65458668af073", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/279d54fea31797fd6de09a083db7b751f83d522958eb0872d2b6b66287357dab5760fbd7400824af649704640ebbdc3d1e67e33f1326a57f007f41615c7bb311", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7fd6a7c921f5d325c3f7fcb3ec326ff5788116454007257eb23ef9c35fe3c5b2a1e018431d8521cd0c16966958c9199ba696322b936aec97902af047376b8d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77b3abf484a3bed1bd830001fe744e0fd670a9136d8c0b8768cc3ea031e7f2674ee87271d8d619b13792d17e6fd5c093b30e1488aa4b3fc990a316e16209459"} 19:08:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:08:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:08:02 INFO - Contents: 19:08:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf9eafe9e5aa98f15a6014261e55229e22a87135b890a7c21cb60c40eba2cc62b979926f73e3f582e521d6ff1eb792b83a9b3f7b796612e94bd65458668af073", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/279d54fea31797fd6de09a083db7b751f83d522958eb0872d2b6b66287357dab5760fbd7400824af649704640ebbdc3d1e67e33f1326a57f007f41615c7bb311", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7fd6a7c921f5d325c3f7fcb3ec326ff5788116454007257eb23ef9c35fe3c5b2a1e018431d8521cd0c16966958c9199ba696322b936aec97902af047376b8d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77b3abf484a3bed1bd830001fe744e0fd670a9136d8c0b8768cc3ea031e7f2674ee87271d8d619b13792d17e6fd5c093b30e1488aa4b3fc990a316e16209459"} 19:08:02 INFO - Running post-run listener: copy_logs_to_upload_dir 19:08:02 INFO - Copying logs to upload dir... 19:08:02 INFO - mkdir: /builds/slave/test/build/upload/logs 19:08:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.794154 ========= master_lag: 1.31 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-31 19:08:03.635665) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2017-05-31 19:08:03.641721) ========= 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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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/cf9eafe9e5aa98f15a6014261e55229e22a87135b890a7c21cb60c40eba2cc62b979926f73e3f582e521d6ff1eb792b83a9b3f7b796612e94bd65458668af073", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/279d54fea31797fd6de09a083db7b751f83d522958eb0872d2b6b66287357dab5760fbd7400824af649704640ebbdc3d1e67e33f1326a57f007f41615c7bb311", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7fd6a7c921f5d325c3f7fcb3ec326ff5788116454007257eb23ef9c35fe3c5b2a1e018431d8521cd0c16966958c9199ba696322b936aec97902af047376b8d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77b3abf484a3bed1bd830001fe744e0fd670a9136d8c0b8768cc3ea031e7f2674ee87271d8d619b13792d17e6fd5c093b30e1488aa4b3fc990a316e16209459"} build_url:https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011073 build_url: 'https://queue.taskcluster.net/v1/task/L3vt00xtTVy66ZLcyENLqg/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/cf9eafe9e5aa98f15a6014261e55229e22a87135b890a7c21cb60c40eba2cc62b979926f73e3f582e521d6ff1eb792b83a9b3f7b796612e94bd65458668af073", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/279d54fea31797fd6de09a083db7b751f83d522958eb0872d2b6b66287357dab5760fbd7400824af649704640ebbdc3d1e67e33f1326a57f007f41615c7bb311", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7fd6a7c921f5d325c3f7fcb3ec326ff5788116454007257eb23ef9c35fe3c5b2a1e018431d8521cd0c16966958c9199ba696322b936aec97902af047376b8d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77b3abf484a3bed1bd830001fe744e0fd670a9136d8c0b8768cc3ea031e7f2674ee87271d8d619b13792d17e6fd5c093b30e1488aa4b3fc990a316e16209459"}' ========= master_lag: 3.40 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2017-05-31 19:08:07.052060) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 19:08:07.056433) ========= 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.zuD0Qv7qfX/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.35OlLvIv3J/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005226 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 19:08:07.087871) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 19:08:07.088192) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 19:08:07.091552) ========= ========= Total master_lag: 4.80 =========