builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0297 starttime: 1497393822.86 results: success (0) revision: 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:42.859182) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:42.859736) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:42.860036) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:42.885068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:42.885481) ========= 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.QsUhTX75Xl/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.O1PLTVUN13/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-13 15:43:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2017-06-13 15:43:43 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130006 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:43.038464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:43.038846) ========= 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.QsUhTX75Xl/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.O1PLTVUN13/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.027356 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:43.087808) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 15:43:43.088204) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QsUhTX75Xl/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.O1PLTVUN13/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-13 15:43:43,188 truncating revision to first 12 chars 2017-06-13 15:43:43,188 Setting DEBUG logging. 2017-06-13 15:43:43,188 attempt 1/10 2017-06-13 15:43:43,188 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0a00302fa5cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 15:43:44,403 unpacking tar archive at: mozilla-beta-0a00302fa5cc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.507032 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 15:43:44.617642) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:44.617995) ========= script_repo_revision: 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:44.618444) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:44.618721) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 15:43:44.638578) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 9 secs) (at 2017-06-13 15:43:44.639009) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QsUhTX75Xl/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.O1PLTVUN13/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:43:44 INFO - MultiFileLogger online at 20170613 15:43:44 in /builds/slave/test 15:43:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:43:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:43:44 INFO - {'append_to_log': False, 15:43:44 INFO - 'base_work_dir': '/builds/slave/test', 15:43:44 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:43:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:43:44 INFO - 'branch': 'Mozilla-Beta', 15:43:44 INFO - 'buildbot_json_path': 'buildprops.json', 15:43:44 INFO - 'config_files': ('talos/mac_config.py',), 15:43:44 INFO - 'default_actions': ('clobber', 15:43:44 INFO - 'read-buildbot-config', 15:43:44 INFO - 'download-and-extract', 15:43:44 INFO - 'populate-webroot', 15:43:44 INFO - 'create-virtualenv', 15:43:44 INFO - 'install', 15:43:44 INFO - 'setup-mitmproxy', 15:43:44 INFO - 'run-tests'), 15:43:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:43:44 INFO - 'download_minidump_stackwalk': True, 15:43:44 INFO - 'download_symbols': 'ondemand', 15:43:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:43:44 INFO - 'tooltool.py': '/tools/tooltool.py', 15:43:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:43:44 INFO - '/tools/misc-python/virtualenv.py')}, 15:43:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:43:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:43:44 INFO - 'gecko_profile': False, 15:43:44 INFO - 'gecko_profile_interval': 0, 15:43:44 INFO - 'installer_path': 'installer.exe', 15:43:44 INFO - 'log_level': 'info', 15:43:44 INFO - 'log_name': 'talos', 15:43:44 INFO - 'log_to_console': True, 15:43:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:43:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:43:44 INFO - 'opt_config_files': (), 15:43:44 INFO - 'pip_index': False, 15:43:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:43:44 INFO - 'cmd': ('bash', 15:43:44 INFO - '-c', 15:43:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:43:44 INFO - 'enabled': True, 15:43:44 INFO - 'halt_on_failure': False, 15:43:44 INFO - 'name': 'check_screen_resolution'},), 15:43:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:43:44 INFO - 'cmd': ('bash', 15:43:44 INFO - '-c', 15:43:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:43:44 INFO - 'enabled': True, 15:43:44 INFO - 'halt_on_failure': False, 15:43:44 INFO - 'name': 'check_screen_resolution'},), 15:43:44 INFO - 'run_cmd_checks_enabled': True, 15:43:44 INFO - 'suite': 'dromaeojs-e10s', 15:43:44 INFO - 'system_bits': '32', 15:43:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:43:44 INFO - 'title': 't-yosemite-r7-0297', 15:43:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:43:44 INFO - 'use_talos_json': True, 15:43:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:43:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:43:44 INFO - 'work_dir': 'build'} 15:43:44 INFO - [mozharness: 2017-06-13 22:43:44.845590Z] Running clobber step. 15:43:44 INFO - Running pre-action listener: _resource_record_pre_action 15:43:44 INFO - Running main action method: clobber 15:43:44 INFO - rmtree: /builds/slave/test/build 15:43:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:43:47 INFO - Running post-action listener: _resource_record_post_action 15:43:47 INFO - [mozharness: 2017-06-13 22:43:47.308990Z] Finished clobber step (success) 15:43:47 INFO - [mozharness: 2017-06-13 22:43:47.309154Z] Running read-buildbot-config step. 15:43:47 INFO - Running pre-action listener: _resource_record_pre_action 15:43:47 INFO - Running main action method: read_buildbot_config 15:43:47 INFO - Using buildbot properties: 15:43:47 INFO - { 15:43:47 INFO - "project": "", 15:43:47 INFO - "product": "firefox", 15:43:47 INFO - "who": "mtabara@mozilla.com", 15:43:47 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 15:43:47 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:43:47 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 15:43:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:43:47 INFO - "stage_platform": "macosx64", 15:43:47 INFO - "basedir": "/builds/slave/test", 15:43:47 INFO - "buildnumber": 1, 15:43:47 INFO - "platform": "yosemite_r7", 15:43:47 INFO - "parent_task_id": "eBg7TPdLQxSKEQ0cweLpmg", 15:43:47 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:43:47 INFO - "slavebuilddir": "test", 15:43:47 INFO - "taskId": "XRU7ADdJRaWSlgAaiaARzA", 15:43:47 INFO - "branch": "mozilla-beta", 15:43:47 INFO - "script_repo_revision": "production", 15:43:47 INFO - "revision": "0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416", 15:43:47 INFO - "slavename": "t-yosemite-r7-0297", 15:43:47 INFO - "repo_path": "releases/mozilla-beta" 15:43:47 INFO - } 15:43:47 INFO - Finding installer, test and symbols from parent task. 15:43:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XRU7ADdJRaWSlgAaiaARzA'}, attempt #1 15:43:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XRU7ADdJRaWSlgAaiaARzA'}, attempt #1 15:43:48 INFO - Task dependencies: Ga43ZkAERxqysLJ81ywa-g eBg7TPdLQxSKEQ0cweLpmg 15:43:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg'}, attempt #1 15:43:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:43:49 INFO - Set test_url: https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/test_packages.json 15:43:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/target.crashreporter-symbols.zip 15:43:49 INFO - Running post-action listener: _resource_record_post_action 15:43:49 INFO - [mozharness: 2017-06-13 22:43:49.097548Z] Finished read-buildbot-config step (success) 15:43:49 INFO - [mozharness: 2017-06-13 22:43:49.097661Z] Running download-and-extract step. 15:43:49 INFO - Running pre-action listener: _resource_record_pre_action 15:43:49 INFO - Running main action method: download_and_extract 15:43:49 INFO - mkdir: /builds/slave/test/build/tests 15:43:49 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')]} 15:43:49 INFO - trying https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 15:43:49 INFO - Downloading https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 15:43:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 15:43:49 INFO - Downloaded 1290 bytes. 15:43:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 15:43:49 INFO - Using the following test package requirements: 15:43:49 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 15:43:49 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:43:49 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 15:43:49 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 15:43:49 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:43:49 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 15:43:49 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:43:49 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 15:43:49 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 15:43:49 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 15:43:49 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 15:43:49 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 15:43:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:43:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:43:49 INFO - Fetch https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:43:51 INFO - Content-Length response header: 58980773 15:43:51 INFO - Bytes received: 58980773 15:44:09 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:44:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:44:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:44:10 INFO - Fetch https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:44:12 INFO - Content-Length response header: 58980773 15:44:12 INFO - Bytes received: 58980773 15:44:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 15:44:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 15:44:29 INFO - Fetch https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 15:44:30 INFO - Content-Length response header: 14450018 15:44:30 INFO - Bytes received: 14450018 15:44:30 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')]} 15:44:30 INFO - trying https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:44:30 INFO - Downloading https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 15:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 15:44:32 INFO - Downloaded 51079660 bytes. 15:44:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:44:32 INFO - mkdir: /builds/slave/test/properties 15:44:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:44:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:44:32 INFO - Contents: 15:44:32 INFO - build_url:https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:44:32 INFO - Running post-action listener: _resource_record_post_action 15:44:32 INFO - Running post-action listener: set_extra_try_arguments 15:44:32 INFO - [mozharness: 2017-06-13 22:44:32.332228Z] Finished download-and-extract step (success) 15:44:32 INFO - [mozharness: 2017-06-13 22:44:32.332345Z] Running populate-webroot step. 15:44:32 INFO - Running pre-action listener: _resource_record_pre_action 15:44:32 INFO - Running main action method: populate_webroot 15:44:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:44:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:44:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:44:32 INFO - '--tppagecycles', 15:44:32 INFO - '7'], 15:44:32 INFO - 'tests': ['tsvgm']}}, 15:44:32 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:44:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:44:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:44:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:44:32 INFO - 'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:44:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:44:32 INFO - 'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['damp', 'tps']}, 15:44:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:44:32 INFO - 'tests': ['damp', 'tps']}, 15:44:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['dromaeo_dom']}, 15:44:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:44:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:44:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:44:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:44:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:44:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['a11yr', 15:44:32 INFO - 'ts_paint', 15:44:32 INFO - 'tpaint', 15:44:32 INFO - 'sessionrestore', 15:44:32 INFO - 'sessionrestore_no_auto_restore', 15:44:32 INFO - 'tabpaint']}, 15:44:32 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:44:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:44:32 INFO - 'bloom_basic_ref']}, 15:44:32 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:44:32 INFO - 'talos_options': ['--mitmproxy', 15:44:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:44:32 INFO - '--firstNonBlankPaint'], 15:44:32 INFO - 'tests': ['quantum_pageload_google', 15:44:32 INFO - 'quantum_pageload_youtube', 15:44:32 INFO - 'quantum_pageload_amazon', 15:44:32 INFO - 'quantum_pageload_facebook']}, 15:44:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['tsvgx', 15:44:32 INFO - 'tsvgr_opacity', 15:44:32 INFO - 'tart', 15:44:32 INFO - 'tscrollx', 15:44:32 INFO - 'cart', 15:44:32 INFO - 'tsvg_static']}, 15:44:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:44:32 INFO - 'tsvgr_opacity', 15:44:32 INFO - 'tart', 15:44:32 INFO - 'tscrollx', 15:44:32 INFO - 'cart', 15:44:32 INFO - 'tsvg_static']}, 15:44:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:44:32 INFO - 'talos_options': ['--disable-e10s'], 15:44:32 INFO - 'tests': ['tp5o']}, 15:44:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:44:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:44:32 INFO - 'talos_options': ['--disable-e10s', 15:44:32 INFO - '--xperf_path', 15:44:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:32 INFO - 'tests': ['tp5n']}, 15:44:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:44:32 INFO - 'talos_options': ['--xperf_path', 15:44:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:32 INFO - 'tests': ['tp5n']}}, 15:44:32 INFO - 'talos.zip': {'path': '', 15:44:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:44:32 INFO - Running post-action listener: _resource_record_post_action 15:44:32 INFO - [mozharness: 2017-06-13 22:44:32.355025Z] Finished populate-webroot step (success) 15:44:32 INFO - [mozharness: 2017-06-13 22:44:32.355164Z] Running create-virtualenv step. 15:44:32 INFO - Running pre-action listener: _resource_record_pre_action 15:44:32 INFO - Running main action method: create_virtualenv 15:44:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:44:32 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 15:44:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:44:32 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:44:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:44:32 INFO - Using real prefix '/tools/python27' 15:44:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:44:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:44:36 INFO - Installing pip.................done. 15:44:36 INFO - Return code: 0 15:44:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:44:36 INFO - Reading from file tmpfile_stdout 15:44:36 INFO - Output received: 15:44:36 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) 15:44:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:44:36 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')]} 15:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:36 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')]} 15:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:36 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 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44:36 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 15:44:36 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 15:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:36 INFO - 'HOME': '/Users/cltbld', 15:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:36 INFO - 'LOGNAME': 'cltbld', 15:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:36 INFO - 'MOZ_NO_REMOTE': '1', 15:44:36 INFO - 'NO_EM_RESTART': '1', 15:44:36 INFO - 'PAGER': '/bin/cat', 15:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:36 INFO - 'PWD': '/builds/slave/test', 15:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:36 INFO - 'SHELL': '/bin/bash', 15:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:36 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:36 INFO - 'USER': 'cltbld', 15:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:36 INFO - 'XPC_FLAGS': '0x0', 15:44:36 INFO - 'XPC_SERVICE_NAME': '0', 15:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:36 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 15:44:36 INFO - Cleaning up... 15:44:36 INFO - Return code: 0 15:44:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:44:36 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')]} 15:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:36 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')]} 15:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:36 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 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44:36 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 15:44:36 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 15:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:36 INFO - 'HOME': '/Users/cltbld', 15:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:36 INFO - 'LOGNAME': 'cltbld', 15:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:36 INFO - 'MOZ_NO_REMOTE': '1', 15:44:36 INFO - 'NO_EM_RESTART': '1', 15:44:36 INFO - 'PAGER': '/bin/cat', 15:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:36 INFO - 'PWD': '/builds/slave/test', 15:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:36 INFO - 'SHELL': '/bin/bash', 15:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:36 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:36 INFO - 'USER': 'cltbld', 15:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:36 INFO - 'XPC_FLAGS': '0x0', 15:44:36 INFO - 'XPC_SERVICE_NAME': '0', 15:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:36 INFO - Downloading/unpacking psutil>=3.1.1 15:44:36 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 15:44:36 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 15:44:36 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 15:44:36 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 15:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:44:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:38 INFO - Installing collected packages: psutil 15:44:38 INFO - Running setup.py install for psutil 15:44:38 INFO - building 'psutil._psutil_osx' extension 15:44:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:44:39 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 15:44:39 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 15:44:39 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 15:44:39 INFO - building 'psutil._psutil_posix' extension 15:44:39 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 15:44:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:44:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:44:39 INFO - ^ 15:44:39 INFO - 1 warning generated. 15:44:39 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 15:44:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:40 INFO - Successfully installed psutil 15:44:40 INFO - Cleaning up... 15:44:40 INFO - Return code: 0 15:44:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44: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')]} 15:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:44: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 mozsystemmonitor==0.3 15:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:40 INFO - 'HOME': '/Users/cltbld', 15:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:40 INFO - 'LOGNAME': 'cltbld', 15:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:40 INFO - 'MOZ_NO_REMOTE': '1', 15:44:40 INFO - 'NO_EM_RESTART': '1', 15:44:40 INFO - 'PAGER': '/bin/cat', 15:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:40 INFO - 'PWD': '/builds/slave/test', 15:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:40 INFO - 'SHELL': '/bin/bash', 15:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:40 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:40 INFO - 'USER': 'cltbld', 15:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:40 INFO - 'XPC_FLAGS': '0x0', 15:44:40 INFO - 'XPC_SERVICE_NAME': '0', 15:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:44: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 15:44: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 15:44: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 15:44: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 15:44:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:44:42 INFO - Installing collected packages: mozsystemmonitor 15:44:42 INFO - Running setup.py install for mozsystemmonitor 15:44:43 INFO - Successfully installed mozsystemmonitor 15:44:43 INFO - Cleaning up... 15:44:43 INFO - Return code: 0 15:44:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44: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')]} 15:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44: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', '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 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:44: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 jsonschema==2.5.1 15:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:43 INFO - 'HOME': '/Users/cltbld', 15:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:43 INFO - 'LOGNAME': 'cltbld', 15:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:43 INFO - 'MOZ_NO_REMOTE': '1', 15:44:43 INFO - 'NO_EM_RESTART': '1', 15:44:43 INFO - 'PAGER': '/bin/cat', 15:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:43 INFO - 'PWD': '/builds/slave/test', 15:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:43 INFO - 'SHELL': '/bin/bash', 15:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:43 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:43 INFO - 'USER': 'cltbld', 15:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:43 INFO - 'XPC_FLAGS': '0x0', 15:44:43 INFO - 'XPC_SERVICE_NAME': '0', 15:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:43 INFO - Downloading/unpacking jsonschema==2.5.1 15:44: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 15:44: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 15:44: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 15:44: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 15:44:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:44:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:44 INFO - Downloading functools32-3.2.3-2.tar.gz 15:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:44:44 INFO - warning: no files found matching '*.txt' 15:44:44 INFO - no previously-included directories found matching 'build' 15:44:44 INFO - no previously-included directories found matching 'dist' 15:44:44 INFO - no previously-included directories found matching '.git*' 15:44:44 INFO - Installing collected packages: jsonschema, functools32 15:44:45 INFO - Running setup.py install for functools32 15:44:45 INFO - warning: no files found matching '*.txt' 15:44:45 INFO - no previously-included directories found matching 'build' 15:44:45 INFO - no previously-included directories found matching 'dist' 15:44:45 INFO - no previously-included directories found matching '.git*' 15:44:45 INFO - Successfully installed jsonschema functools32 15:44:45 INFO - Cleaning up... 15:44:45 INFO - Return code: 0 15:44:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:44:45 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')]} 15:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:45 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')]} 15:44:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:45 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 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44:45 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 15:44:45 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 15:44:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:45 INFO - 'HOME': '/Users/cltbld', 15:44:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:45 INFO - 'LOGNAME': 'cltbld', 15:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:45 INFO - 'MOZ_NO_REMOTE': '1', 15:44:45 INFO - 'NO_EM_RESTART': '1', 15:44:45 INFO - 'PAGER': '/bin/cat', 15:44:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:45 INFO - 'PWD': '/builds/slave/test', 15:44:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:45 INFO - 'SHELL': '/bin/bash', 15:44:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:45 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:45 INFO - 'USER': 'cltbld', 15:44:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:45 INFO - 'XPC_FLAGS': '0x0', 15:44:45 INFO - 'XPC_SERVICE_NAME': '0', 15:44:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:44:45 INFO - Cleaning up... 15:44:45 INFO - Return code: 0 15:44:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:44:45 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')]} 15:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:45 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')]} 15:44:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:45 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 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44:45 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 15:44:45 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 15:44:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:45 INFO - 'HOME': '/Users/cltbld', 15:44:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:45 INFO - 'LOGNAME': 'cltbld', 15:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:45 INFO - 'MOZ_NO_REMOTE': '1', 15:44:45 INFO - 'NO_EM_RESTART': '1', 15:44:45 INFO - 'PAGER': '/bin/cat', 15:44:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:45 INFO - 'PWD': '/builds/slave/test', 15:44:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:45 INFO - 'SHELL': '/bin/bash', 15:44:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:45 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:45 INFO - 'USER': 'cltbld', 15:44:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:45 INFO - 'XPC_FLAGS': '0x0', 15:44:45 INFO - 'XPC_SERVICE_NAME': '0', 15:44:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:45 INFO - Downloading/unpacking blobuploader==1.2.4 15:44: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 15:44: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 15:44: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 15:44: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 15:44:47 INFO - Downloading blobuploader-1.2.4.tar.gz 15:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:44:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:44:47 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 15:44:47 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 15:44:47 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 15:44:47 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 15:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:44:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:44:47 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 15:44:47 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 15:44:47 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 15:44:47 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 15:44:47 INFO - Downloading docopt-0.6.1.tar.gz 15:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:44:48 INFO - Installing collected packages: blobuploader, requests, docopt 15:44:48 INFO - Running setup.py install for blobuploader 15:44:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:44:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:44:48 INFO - Running setup.py install for requests 15:44:48 INFO - Running setup.py install for docopt 15:44:48 INFO - Successfully installed blobuploader requests docopt 15:44:48 INFO - Cleaning up... 15:44:48 INFO - Return code: 0 15:44:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:48 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 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44:48 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 15:44:48 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 15:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:48 INFO - 'HOME': '/Users/cltbld', 15:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:48 INFO - 'LOGNAME': 'cltbld', 15:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:48 INFO - 'MOZ_NO_REMOTE': '1', 15:44:48 INFO - 'NO_EM_RESTART': '1', 15:44:48 INFO - 'PAGER': '/bin/cat', 15:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:48 INFO - 'PWD': '/builds/slave/test', 15:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:48 INFO - 'SHELL': '/bin/bash', 15:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:48 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:48 INFO - 'USER': 'cltbld', 15:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:48 INFO - 'XPC_FLAGS': '0x0', 15:44:48 INFO - 'XPC_SERVICE_NAME': '0', 15:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-QzL8LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-OFAHWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-rSWOdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-9EkzHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-EpZFHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-clk7z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-eREi3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Ekqnb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:49 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-UERalg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-N6L9EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-VzNYoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-O7qpSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-IGIe02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-5tnnep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-HLesYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-FZUOEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:50 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-yALBQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:44:50 INFO - Running setup.py install for manifestparser 15:44:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:44:51 INFO - Running setup.py install for mozcrash 15:44:51 INFO - Running setup.py install for mozdebug 15:44:51 INFO - Running setup.py install for mozdevice 15:44:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:44:51 INFO - Running setup.py install for mozfile 15:44:51 INFO - Running setup.py install for mozhttpd 15:44:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:44:51 INFO - Running setup.py install for mozinfo 15:44:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:44:51 INFO - Running setup.py install for mozInstall 15:44:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Running setup.py install for mozleak 15:44:52 INFO - Running setup.py install for mozlog 15:44:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Running setup.py install for moznetwork 15:44:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Running setup.py install for mozprocess 15:44:52 INFO - Running setup.py install for mozprofile 15:44:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:44:52 INFO - Running setup.py install for mozrunner 15:44:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:44:53 INFO - Running setup.py install for mozscreenshot 15:44:53 INFO - Running setup.py install for moztest 15:44:53 INFO - Running setup.py install for mozversion 15:44:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:44:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:44:53 INFO - Cleaning up... 15:44:53 INFO - Return code: 0 15:44:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:53 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')]} 15:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:53 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')]} 15:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:53 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 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44:53 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 15:44:53 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 15:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:53 INFO - 'HOME': '/Users/cltbld', 15:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:53 INFO - 'LOGNAME': 'cltbld', 15:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:53 INFO - 'MOZ_NO_REMOTE': '1', 15:44:53 INFO - 'NO_EM_RESTART': '1', 15:44:53 INFO - 'PAGER': '/bin/cat', 15:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:53 INFO - 'PWD': '/builds/slave/test', 15:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:53 INFO - 'SHELL': '/bin/bash', 15:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:53 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:53 INFO - 'USER': 'cltbld', 15:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:53 INFO - 'XPC_FLAGS': '0x0', 15:44:53 INFO - 'XPC_SERVICE_NAME': '0', 15:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:53 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-GHK4x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:53 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)) 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:53 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-pi6UyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:53 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)) 15:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:53 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-jNTtdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:54 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)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-yHs8uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:54 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)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Igx5S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:54 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)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-41e3OC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:54 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)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-y1AKBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-jzdfCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-DWUbz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:54 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)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-xdmtDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:54 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)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-AoJw8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:54 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)) 15:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:54 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-YAOfUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:55 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)) 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-GLKo7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:55 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)) 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-O2J9Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:55 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)) 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-qllQdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:55 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)) 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-l6ABSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:55 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)) 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:55 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Sz_dyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:55 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)) 15:44:55 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)) 15:44:55 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)) 15:44:55 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)) 15:44:55 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)) 15:44:55 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)) 15:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:44:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:56 INFO - Downloading blessings-1.6.tar.gz 15:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:44:57 INFO - Installing collected packages: blessings 15:44:57 INFO - Running setup.py install for blessings 15:44:57 INFO - Successfully installed blessings 15:44:57 INFO - Cleaning up... 15:44:57 INFO - Return code: 0 15:44:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:44:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:44:57 INFO - Reading from file tmpfile_stdout 15:44:57 INFO - Current package versions: 15:44:57 INFO - blessings == 1.6 15:44:57 INFO - blobuploader == 1.2.4 15:44:57 INFO - docopt == 0.6.1 15:44:57 INFO - functools32 == 3.2.3-2 15:44:57 INFO - jsonschema == 2.5.1 15:44:57 INFO - manifestparser == 1.1 15:44:57 INFO - mozInstall == 1.13 15:44:57 INFO - mozcrash == 1.0 15:44:57 INFO - mozdebug == 0.1 15:44:57 INFO - mozdevice == 0.51 15:44:57 INFO - mozfile == 1.2 15:44:57 INFO - mozhttpd == 0.7 15:44:57 INFO - mozinfo == 0.10 15:44:57 INFO - mozleak == 0.1 15:44:57 INFO - mozlog == 3.4 15:44:57 INFO - moznetwork == 0.27 15:44:57 INFO - mozprocess == 0.25 15:44:57 INFO - mozprofile == 0.28 15:44:57 INFO - mozrunner == 6.13 15:44:57 INFO - mozscreenshot == 0.1 15:44:57 INFO - mozsystemmonitor == 0.3 15:44:57 INFO - moztest == 0.8 15:44:57 INFO - mozversion == 1.4 15:44:57 INFO - psutil == 3.1.1 15:44:57 INFO - requests == 1.2.3 15:44:57 INFO - wsgiref == 0.1.2 15:44:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:57 INFO - 'HOME': '/Users/cltbld', 15:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:57 INFO - 'LOGNAME': 'cltbld', 15:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:57 INFO - 'MOZ_NO_REMOTE': '1', 15:44:57 INFO - 'NO_EM_RESTART': '1', 15:44:57 INFO - 'PAGER': '/bin/cat', 15:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:57 INFO - 'PWD': '/builds/slave/test', 15:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:57 INFO - 'SHELL': '/bin/bash', 15:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:57 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:57 INFO - 'USER': 'cltbld', 15:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:57 INFO - 'XPC_FLAGS': '0x0', 15:44:57 INFO - 'XPC_SERVICE_NAME': '0', 15:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:57 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)) 15:44:57 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)) 15:44:57 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)) 15:44:57 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)) 15:44:57 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)) 15:44:57 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)) 15:44:57 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)) 15:44:57 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)) 15:44:57 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)) 15:44:57 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:44:57 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 15:44:57 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 15:44:57 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 15:44:57 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 15:44:59 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:44:59 INFO - Installing collected packages: marionette-driver 15:44:59 INFO - Running setup.py install for marionette-driver 15:44:59 INFO - Successfully installed marionette-driver 15:44:59 INFO - Cleaning up... 15:44:59 INFO - Return code: 0 15:44:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048afc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe07a5963a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, '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.QsUhTX75Xl/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.O1PLTVUN13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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 15:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:59 INFO - 'HOME': '/Users/cltbld', 15:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:59 INFO - 'LOGNAME': 'cltbld', 15:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:59 INFO - 'MOZ_NO_REMOTE': '1', 15:44:59 INFO - 'NO_EM_RESTART': '1', 15:44:59 INFO - 'PAGER': '/bin/cat', 15:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:59 INFO - 'PWD': '/builds/slave/test', 15:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:59 INFO - 'SHELL': '/bin/bash', 15:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:44:59 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:59 INFO - 'USER': 'cltbld', 15:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:59 INFO - 'XPC_FLAGS': '0x0', 15:44:59 INFO - 'XPC_SERVICE_NAME': '0', 15:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:44:59 INFO - Cleaning up... 15:44:59 INFO - Return code: 0 15:44:59 INFO - Running post-action listener: _resource_record_post_action 15:44:59 INFO - Running post-action listener: _start_resource_monitoring 15:44:59 INFO - Starting resource monitoring. 15:44:59 INFO - [mozharness: 2017-06-13 22:44:59.830555Z] Finished create-virtualenv step (success) 15:44:59 INFO - [mozharness: 2017-06-13 22:44:59.831521Z] Running install step. 15:44:59 INFO - Running pre-action listener: _resource_record_pre_action 15:44:59 INFO - Running main action method: install 15:44:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:00 INFO - Reading from file tmpfile_stdout 15:45:00 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:45:00 INFO - Reading from file tmpfile_stdout 15:45:00 INFO - Output received: 15:45:00 INFO - Usage: mozinstall [options] installer 15:45:00 INFO - Options: 15:45:00 INFO - -h, --help show this help message and exit 15:45:00 INFO - -d DEST, --destination=DEST 15:45:00 INFO - Directory to install application into. [default: 15:45:00 INFO - "/builds/slave/test"] 15:45:00 INFO - --app=APP Application being installed. [default: firefox] 15:45:00 INFO - mkdir: /builds/slave/test/build/application 15:45:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:45:15 INFO - Reading from file tmpfile_stdout 15:45:15 INFO - Output received: 15:45:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:45:15 INFO - Running post-action listener: _resource_record_post_action 15:45:15 INFO - [mozharness: 2017-06-13 22:45:15.961286Z] Finished install step (success) 15:45:15 INFO - [mozharness: 2017-06-13 22:45:15.961429Z] Running setup-mitmproxy step. 15:45:15 INFO - Running pre-action listener: _resource_record_pre_action 15:45:15 INFO - Running main action method: setup_mitmproxy 15:45:15 INFO - Skipping: mitmproxy is not required 15:45:15 INFO - Running post-action listener: _resource_record_post_action 15:45:15 INFO - [mozharness: 2017-06-13 22:45:15.962190Z] Finished setup-mitmproxy step (success) 15:45:15 INFO - [mozharness: 2017-06-13 22:45:15.962325Z] Running run-tests step. 15:45:15 INFO - Running pre-action listener: _resource_record_pre_action 15:45:15 INFO - Running main action method: run_tests 15:45:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:45:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:45:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:45:15 INFO - 2017-06-13 15:45:15.976 screenresolution[1634:7419] starting screenresolution argv=screenresolution get 15:45:15 INFO - 2017-06-13 15:45:15.979 screenresolution[1634:7419] Display 0: 1600x1200x32@60 15:45:15 INFO - 2017-06-13 15:45:15.985 screenresolution[1635:7421] starting screenresolution argv=screenresolution list 15:45:15 INFO - Available Modes on Display 0 15:45:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:45:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:45:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:45:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:45:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:45:16 INFO - Intel Iris: 15:45:16 INFO - Chipset Model: Intel Iris 15:45:16 INFO - Type: GPU 15:45:16 INFO - Bus: Built-In 15:45:16 INFO - VRAM (Dynamic, Max): 1536 MB 15:45:16 INFO - Vendor: Intel (0x8086) 15:45:16 INFO - Device ID: 0x0a2e 15:45:16 INFO - Revision ID: 0x0009 15:45:16 INFO - Displays: 15:45:16 INFO - Display: 15:45:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:45:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:45:16 INFO - Display Serial Number: 1600x1200 60 15:45:16 INFO - Main Display: Yes 15:45:16 INFO - Mirror: Off 15:45:16 INFO - Online: Yes 15:45:16 INFO - Rotation: Supported 15:45:16 INFO - Return code: 0 15:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:45:16 INFO - Python 2.7.3 15:45:16 INFO - Return code: 0 15:45:16 INFO - grabbing minidump binary from tooltool 15:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:16 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 0x104605d30>, '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 0x1045cfcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045f6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:45:16 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 15:45:16 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 15:45:16 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 15:45:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:45:16 INFO - Return code: 0 15:45:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:45:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:45:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:45:16 INFO - ENV: RUST_BACKTRACE is now 1 15:45:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:45:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--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 15:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0297 --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 15:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QsUhTX75Xl/Render', 15:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:16 INFO - 'HOME': '/Users/cltbld', 15:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:16 INFO - 'LOGNAME': 'cltbld', 15:45:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:45:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:16 INFO - 'MOZ_NO_REMOTE': '1', 15:45:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:45:16 INFO - 'NO_EM_RESTART': '1', 15:45:16 INFO - 'PAGER': '/bin/cat', 15:45:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:16 INFO - 'PWD': '/builds/slave/test', 15:45:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:16 INFO - 'RUST_BACKTRACE': '1', 15:45:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:45:16 INFO - 'SHELL': '/bin/bash', 15:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O1PLTVUN13/Listeners', 15:45:16 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 15:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:16 INFO - 'USER': 'cltbld', 15:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:16 INFO - 'XPC_FLAGS': '0x0', 15:45:16 INFO - 'XPC_SERVICE_NAME': '0', 15:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--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 15:45:16 INFO - mozversion application_buildid: 20170613142714 15:45:16 INFO - mozversion application_changeset: 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 15:45:16 INFO - mozversion application_display_name: Firefox 15:45:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:45:16 INFO - mozversion application_name: Firefox 15:45:16 INFO - mozversion application_remotingname: firefox 15:45:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:45:16 INFO - mozversion application_vendor: Mozilla 15:45:16 INFO - mozversion application_version: 55.0 15:45:16 INFO - mozversion platform_buildid: 20170613142714 15:45:16 INFO - mozversion platform_changeset: 0a00302fa5cc6fa1551a33dbe9b850a1fc2ab416 15:45:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:45:16 INFO - mozversion platform_version: 55.0 15:45:16 INFO - using testdate: 1497393916 15:45:16 INFO - actual date: 1497393916 15:45:16 INFO - starting webserver on 'localhost:49229' 15:45:16 INFO - SUITE-START | Running 2 tests 15:45:16 INFO - TEST-START | dromaeo_css 15:45:16 INFO - operating with platform_type : mac_ 15:45:16 INFO - Initialising browser for dromaeo_css test... 15:45:16 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpTG95TT/profile 15:45:16 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 15:45:49 INFO - TEST-INFO | 1645: killed by SIGTERM 15:45:49 INFO - Browser initialized. 15:45:49 INFO - Running cycle 1/1 for dromaeo_css test... 15:45:49 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpTG95TT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:51 INFO - PID 1655 | 2017-06-13 15:45:51.136 plugin-container[1656:7982] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x922b, name = 'com.apple.tsm.portname' 15:45:51 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:51 INFO - PID 1655 | 2017-06-13 15:45:51.137 plugin-container[1656:7982] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 15:45:51 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:51 INFO - PID 1655 | 2017-06-13 15:45:51.137 plugin-container[1656:7982] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:46:01 INFO - PID 1655 | RSS: Main: 184893440 15:46:01 INFO - PID 1655 | 15:49:04 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 15:49:06 INFO - PID 1655 | RSS: Main: 199368704 15:49:06 INFO - PID 1655 | 15:52:08 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 15:52:08 INFO - PID 1655 | RSS: Main: 209100800 15:52:08 INFO - PID 1655 | 15:55:10 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 15:55:11 INFO - PID 1655 | RSS: Main: 211578880 15:55:11 INFO - PID 1655 | 15:58:12 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 15:58:13 INFO - PID 1655 | RSS: Main: 213200896 15:58:13 INFO - PID 1655 | 16:01:15 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 16:01:16 INFO - PID 1655 | RSS: Main: 214806528 16:01:16 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 16:04:18 INFO - PID 1655 | RSS: Main: 212381696 16:04:18 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | __start_tp_report 16:04:18 INFO - PID 1655 | _x_x_mozilla_page_load 16:04:18 INFO - PID 1655 | _x_x_mozilla_page_load_details 16:04:18 INFO - PID 1655 | |i|pagename|runs| 16:04:18 INFO - PID 1655 | |0;dojo.html;3690.501782259394;3691.649293317137;3742.8128593570323;3823.655870971612;3827.559830619478;6277.874442511153;6304.464120549759;6316.652584107872;6412.102305677209;6466.547341686084;6381.680915954207;6401.551891367594;6433.549651524383;6435.034744788272;6440.227172739266;5535.424029585876;5611.663300201981;5673.886522269573;5767.971160144193;5823.854403639901;5700.97382471155;5732.398098199692;5851.063829787235;5856.180134781134;5870.530357571384;5509.559235261169;5552.3892371839465;5610.382857885629;5662.943370566283;5698.7435565399655;5584.246126772874;5635.520980716644;5698.601097923147;5703.914441283384;5751.539876809887;5622.128570071604;5708.257926469532;5720.48515633595;5738.340090889517;5744.310682718101;6332.556721029529;6377.904331434986;6381.866778157695;6415.454686351665;6425;7014.438844892396;7015.824604384931;7029.929700702979;7042.119735033121;7068.823279418004;6777.457803375718;6879.346462086094;6984.126984126984;7086.503944723871;7106.324899134625;6535.117759102555;6564;6572.474202063823;6627.204735431779;6634.23706273775;7094.693930438588;7310.086239220098;7311.0495635568095;7321.267873212636;7323.450741194431;6645.534812563074;6724.731010759613;6744.393004629607;6754.966225168941;6764.763233286812;7030.050943122716;7098.929010709827;7114.964425177945;7126.679299431538;7136.7145314186855;5716.914246286309;6945.89581156283;6984.301569842975;6985.091938048021;6991.30086991307;6740.359665831738;7009.334113259232;7027.051348068047;7036.964815175891;7038.120234970629;5470.671759694431;5496.395396506381;5515.61390702647;5523.199136125239;5523.944760552424;4395.648348132142;4896.118698634209;4960.280759289883;5067.543921047123;5067.87330316745;6082.9695851520455;6562.475001999828;6688.765893193812;6696.531242813054;6715.1941766988275;7171.964140179266;7208.026916366328;7212.675429605681;7218.3503484686635;7232.783016509513;6425;6662.433693136138;6673.4661227101715;6692.933070669328;6715.4291885190305;6133.938660613337;6140.9030504681;6150.446459818638;6162;6187.102870083902;7271.709131634675;7341.816454588571;7369.926300737031;7386.96306518464;7398.667059982421;7140.428765698835;7184.1738200105565;7186.281371862772;7217;7218.530795498275;7284.817879553054;7319.938608901786;7377.5204611700065;7393.667284972082;7395.963020184865;6669.699863506251;6995.090638217;7048.964755176191;7054.259302773135;7075.327843854826;6934.202566704894;6952.617606031515;7024.8595028098125;7156;7157.856842863218;6049.001914684028;6509.707063182073;6804.183497980274;6878.312168783082;6905.309469053054;6894.551854129466;6927.549709268881;6934.06390137325;6942.548734332252;6960.547564408297;6478.643674597943;6584.209894812653;6642.734290628321;6728.192616886005;6773.390394864289;6757.729690812313;6774.051632771515;6789.592624442549;6797.524173307821;6802.863942721019;6682.599044057372;6693.230278518033;6730.8653826924165;6766.458683305225;6793.015012823212 16:04:18 INFO - PID 1655 | |1;ext.html;816.4896939412866;869.3783944479695;888.942218755781;899.0919171636644;1272.3638180909545;37476.43785343222;41262.79368603157;41306.76079717613;41346.99999999996;41461.58538414615;41880.74357769264;42352;42379.57620423795;42447.57552424475;42489.787551062276;8251.29863961564;8296.045954715224;8384.580770961458;8384.83230335393;8393.748187554384;8477.406581539295;8706.651733930636;8709.129087091304;8714.172153645393;8719.520426376546;7431.962840185818;8811.647534098629;8812.162844529792;8813.162749538815;8816.986046604658;8710.21608055275;8717.346199035066;8780.47317160966;8783.297336213087;8803.64785408583;7576.659050342748;8393.958030209871;8530.65877364911;8581.914180858173;8603.5698215089;38921.80539097286;39091.41362879531;39221.60778392208;39273;39506.024698764944;3697.0757310672334;3702.6852717518773;3732.869349572779;3735.794531300777;3750.1187221003042;32300.384980750867;32300.546475408428;32356.676433235603;32381.51427728586;32497.86257480977;3216.3245718399166;3251.869925202989;3261.804291742479;3286.0798976286437;3322.18606441421;266188;269750;270728;328584;328973;37725.245495090494;50165.24752128721;50549.49450505521;50561.24158137631;50580;52780.20829687558;52893.73553132287;52913.206301905506;53181.73409133007;53195.73402132965;16300.510984670478;16433.91783041077;16571.91714041446;16584.08787516674;16626.168691565374;13552.593422197153;13584.592462226148;13613.251271179983;13651.795223071662;13693.178409295475;14146.938979576573;14172.85827141736;14267.001309908206;14283.428662853577;14297.64255893611;6020.849478763066;6032.215811944419;6046.274447066292;6058.606190597597;6078.969605151946;954.8519979403204;972.3388096094724;982.1700662939804;1020.4642562654666;1044.2150486305932;21915.452113697283;23276.301710948697;23584.174553890876;23650;24884.760371566645;991.504247876062;1025.7692019295748;1054.1988089052375;1106.336198281025;1173.677238759348;13982.370793314325;15604.765928511082;16162.676746465006;16171.35314587427;16293;371861;373278;375293;375477;377095;53988;54092.45907540874;54126;54480.72759636256;54505.727471363185;1420.2330741399735;1512.6445285357931;1569.4585368048006;1581.320032386062;1584.9286782094835;28410.85794571014;30081.247968800955;30475.847620761753;30525.542116867822;30737.692623073483;38555.22889542137;38587;38601.42097868591;38626.2274754509;38705;307081;307100;307473;307966;308195;30271;30730.84634576813;30731;31408.68591314149;35739.92780216598;42118.94702632459;42745.35881961711;43135.784321078194;43205.783971079945;45393;28979.420411592073;30118.698813012466;30172.84913575506;31121.377572448877;31147.221319467193;30454.086377408712;30547.847260764443;30574.847125765118;30968.360498561877;31079.37841243117;36233.63766362303;36692;37166.44250336193;37791.05522361963;37923.431148533346;29482.705172947997;30042.549361759153;30631;31118.377632446773;31134;22958;24485.26544203677;24832.627510586994;24832.751672483766;24884.751152488967 16:04:18 INFO - PID 1655 | |2;jquery.html;475.7573637444903;542.2110828744178;546.0907588864541;549.5164255455198;550.5017958747333;24538.87730561347;25234.86443110062;25342.36644083899;25348.873255633694;25383.619245711307;24995.50008999819;27685.86157069217;27756.583651245197;27760;27797.86101069492;6776.42400395967;6883.896741548856;6930.549514281581;6937.410320122795;6942.479314051441;6916.585004899722;6968.860622787542;6982.755603553876;6983.930160698379;6986.895196572055;6252.531060170505;6785.1857777066825;6793.694283757218;6826;6849.7602583909575;6810.523263371566;6847.246802851682;6849.383555479981;6879.449644028466;6880.174379074518;6301.432871041582;6305.716242769086;6309.211348581427;6316.810495685137;6323.7786677466065;30254.092377228717;32526.512102318484;32583.837080814443;32989.50515742266;33312.16719582005;2366.8461549999197;2426.1265944260053;2430.86630235337;2432.902683892642;2434.379233295516;20601.072951717208;21115.9442027898;21118.3664490064;21119.15523379063;21131.837748923805;2265.161890100657;2301.79283864453;2320.3271051395077;2321.94195145121;2347.8826058696995;215454;219078.90460547755;223640.99999999837;223922;224389;14487.78268325976;16061.919690401706;16252;16291.511254662379;17290.567735806453;22167.113315467202;22314.553708926054;22359.776402236093;22466.55066898653;22478.55042899133;10085.092341689284;10876.619318323821;11008.284461509977;11061.391623460626;11123.944380278208;9661.613535458644;10192.388456692623;10238.33450825694;10370.170386369073;10423;9679.274054445805;9765.144622521699;9820.116189542976;9834.114929656222;9851.605935762489;2732.699403065661;2754.352727109128;2773.3066733316673;2773.334399744047;2807.354308509054;885.7652722028668;966.3187452845727;975.687779910436;983.3706427886159;1046.9790604188026;15683;20887.164513419597;21603.78396216049;21931.561368772534;22581.4354641132;1000.3047881722279;1014.6195176808696;1026.8440698015445;1057.2123767793043;1068.369661899483;10929.071028962628;11275.830862537068;11329.490172942238;11400.543978240778;11603.303801771921;573688;597369;602616;607587;637882;159092;174499;174768.1261593684;177386;178397;1201.591458903977;1219.2196993923897;1273.1342686972762;1273.5287943460978;1290.6708789258867;22529.21147759886;24271.75728242695;24286.271411857673;24568.508629826945;24614.507709845344;71523.28476715166;74647.25352746402;75234.62382688052;75275;75385.62307188429;88463.1153688455;92773.14453711023;93688;97364.51317743366;97558.51220744135;11972.820407694066;12145.574904878287;12263.509459621515;12284.201526900722;12305.446254918372;14205.786913196516;14450.277486125862;15702.21488925572;18692.34576789806;18774;15231.314590843216;15251.542453726406;15270.312835922188;15271.77092343638;15731.606709832346;13294.401751920994;13325.866741332462;13576.389062492013;14273.500427484989;14368.856311436752;15482.84517154814;15851.445199418426;15970.441034563735;16006.439774607832;16094.195290235675;11911.40442977865;12101;12116.33360165164;12117.575884843987;12176.878231217574;15283.847161528687;15400;15410.229488525305;15439.99640023395;15495.30271137779 16:04:18 INFO - PID 1655 | |3;mootools.html;1420.7087547052856;1455.8325792533863;1463.3122432456748;1478.903871248369;1499.8575135362137;12305;13994.300284985748;14579.854201457982;14595.343209555569;14611.92694036531;13979.23114228717;14745.631359216024;14816.851831481681;14819.48131815384;14859.182744949023;2902.2889392098964;2951.9262018449494;2978.538326559387;2978.8510574471297;2986.462436761382;2683.758461738443;2931.5016447204025;2934.4277865816175;2939.4855900217485;2945.26368407898;2704.9323766905786;2933.779966502521;2945.9263518412;2952.5571164325306;2955.970440295591;2879.58246054322;2898.318895059659;2907.2295841601995;2909.3744844858247;2911.8252904825777;2850.9429811403656;2898.6376702912135;2944.6466424029254;2945.072302224792;2947.0864032150103;14288.499902503363;14351.856481435156;14435.35040923161;14441.927790361085;14492.565223043326;7889.8816517752275;8110.107888132301;8119.675212991473;8149.837003259901;8200.466969646954;4920;5131.461196574343;5240.68555886648;5261.789528418859;5272.868178295535;1475.2844870237925;1490.1059364381392;1506.314626844783;1525.7253694335016;1531.2343828085957;21493.57012859734;21600.351989440343;21609.783902160936;21646.458838529004;21669.783302166936;6066.149824303541;6255.906161407583;6949.756758513528;6983.755568555076;7006.068208857937;14046.508372206923;14991.775123373158;15007.399704011717;15033.022853514487;15042.924785376223;7755.495892766952;7969.481983671159;7993.760187194393;8017;8022.759317220492;6022.608530445507;6233.1273621693;6631.6684165792485;7476.364509016795;7502.924970750332;5325.707086110224;5470.070088084955;5552.888942221133;5643.52030077448;5677.772889084473;1418.1987177244823;1604.310146636934;1606.2530923120805;1642.0968467342914;1642.991785041067;822.43663090783;856.6273670953155;864.6843901975734;868.1318681318681;884.9867251991225;6003.3996600339615;7224.313690199423;7506.399488040944;7565.356944659656;7615.771526854092;693.3090987450161;701.5475018612966;713.653877869233;733.3546479098466;737.8710572823588;5014.648974571818;5085.847424577269;5128.9230661540105;5248.265242866002;5336.199570064521;18840.623187536446;19740.210391584464;19770;19778.406647800588;19785.823559408454;17664.940103593824;18244.90877545604;18856;19572.804271957382;19614.117364718622;9819.705408837746;10199.847002294971;10262.743431414274;10333.586656533806;10359.223058270509;7865.60671966388;8006;8090.6359213836695;8097.554634495159;8548.487090774574;17491.650166996333;17725.379611713528;17729.645407092044;18009.729854051937;18025.549361266072;16815.495535133967;17611.119444027492;17931.5517112068;18396.724049139542;18402.63194736071;9371.062893710574;9661.71014869555;10464.058234758908;10549.841752373566;10550.630727924487;12271.57049503263;12991.155574887602;13449.46202151942;13715.45138194501;13725.794113088512;10498.6588210614;10730.624428144978;10987.39569323695;11014.889851101707;11015.83476247873;10255.794884102426;10297.588096476356;10445.216608754527;10484.266101373136;10849.891501084889;9668.951655241679;9723.46520941355;9747.561359738687;9753.317267791186;9766.658166964406;6865.862682746218;7028.769965256162;7085.716571337089;7159.248278930637;7297.379722723628;6957.512974091765;7208.5314454562385;7217.422606191597;7467.58928258951;7473.626318683935 16:04:18 INFO - PID 1655 | |4;prototype.html;451.1383257977264;464.14365495660513;475.9143354196245;486.2803051483806;494.3425244424913;5418.647787893789;5595.916061259084;6511.446527045201;6550.836229094275;6594.967025164873;5634.521065709409;5758.337791154018;6270.24757029156;6367.153525477617;6371.52751750296;3889.591592882749;4082.7142100053006;4722.197226471508;4833.613310935126;4853.320535125085;4172.97913510432;4196.769177695225;4834.395700537433;4838.661293709441;4861.465238823726;4557.498675145731;4778.880527986811;4787.305840653104;4801.927971080437;4802.351682522867;4790.688605240648;4795.769966965343;4795.808167673289;4797.736124513151;4809.2305231162845;3655.7258205634416;4212.410262563244;4224.281872081753;4237;4237.724547904407;5102.387713474407;5642.379338272786;6243.313235544087;6309.242890853127;6317.272809042687;5664.235328230678;5689.435405263544;5710.628809127394;5716.628419152742;5739.454751798572;6923.619200943946;6935.653217339112;6951.617661028641;6954.756583519553;6980.022796808451;4338.913221735579;4490.708103973231;4855.417349917997;4855.63582731293;4885.462599114095;5605.691686957216;6554.9184384576965;7290.869915163158;7357.41140708742;7459.738909138208;4928.560362620526;4946.703197808143;4953.752312384367;4960.727160006234;4973.378327709036;5020.774065167003;5077.644564880497;5088.872778180501;5097.133487307166;5100.158473851773;5667.3847953333925;5697.230873832063;5700.25896633435;5713.171590119409;5744.281964754406;4995.550400463976;6917.273686262971;6993.70616435961;6996.8950465743055;7123.7230873798635;1253.9811902821466;1713.5373449168655;1730.4771800815454;1739.0348356662173;1741.329588108562;949.3829011142674;1032.8863824979246;1045.5451878432755;1171.701216189866;1180.7461395799944;6048.4858787003595;6066.817995460143;6255.811395834777;6279.425435571394;6595.406413422815;5224.608154388436;6475.7733479328;6950.965245173742;7156.570605763567;7161.606111663908;6061.87855246772;6519.3154718754795;6547.672616369163;6568.86862262752;6587.308332625101;2975.4941659917868;3140.9214769630485;3696.5933747287777;3729.68297694699;3744.981275093607;6398.12084561949;6946.409555187865;7467.357181420079;7481.812954676177;7514.436417268617;6632.568883022588;7392.039034925425;7396.2279237588145;7417.329761286041;7435.665395057235;6723.462123030145;7425.7029718810645;7443.292887175709;7456.4780465367985;7457.962710186414;6879.277675843964;7098.574085554883;7150.463715221275;7162.677679504539;7203.226757698219;6252.374762523711;6362.331955144643;6949.61777102244;6988.161420629353;7034.577925324497;5871.412858714265;7390;7396.667149978156;7415;7438.776836694908;4608.331791890224;4930.605551555938;4935.728534930469;4943.159662857395;4966.155753521838;6551.868962620816;6795.082663840319;6872.62200578973;7170.139583250043;7172.641367931687;5598.396745513356;6288.27684816252;6382.5532212744665;6829.726810927706;6926.307369263033;6836.769381511375;6839.623820689711;6850.691718872762;6854.657267136723;6864.553804002923;6054.939450605438;6158.722857471335;6766;6798.52410331272;6804.251532331538;5833.241678581757;6342.587731797418;6799.286074962058;6941.409980151744;6973.267806880204;6141.9078713818435;6677.565958212701;6701.296363881722;6717.16035495563;6729.158855143107 16:04:18 INFO - PID 1655 | |5;yui.html;861.6553378648543;864.2955990867447;868.5092922498787;880.9383343165977;893.209509584018;17987.280508779648;18186.99971501567;18322.63354732908;18359.54101147472;18362.724559131624;17406.825931740677;18326.633467330645;19544;19579;19599.706004409945;927.2998885841181;1030.1501261459307;1064.2231171244996;1067.738404090995;1068.0814499530397;1063.415121683075;1064.930779499334;1066.4934156275747;1067.941263230522;1075.671920064384;944.8866136063648;1016.11090295991;1130.2766229613055;1130.3387518301804;1133.184107442644;1092.6667366453228;1102.0412241350077;1109.4896347680076;1119.8488204092507;1123.2305870478667;807.563915485637;813.190875079294;814.7840822182127;815.6003558256411;818.7257268814955;18388;18702.345417910445;18878.62242755137;18930.621387572308;19134.80865191344;17690.646187076316;18371.72442413365;18763.249470021183;18824.341148059888;18893.9055304724;15276.770848437282;15282.694346113016;15284.770728439082;15325.927185097047;15372.154531500762;3701.9629803701887;3796.658300752918;3825.540935087807;3843.3466310727244;3849.326367885637;155054;157583;167221.32778672178;171446;175136;14254.501092461715;14499.92750036243;14575;14585.906057045764;14586.197759123137;27211.591826122625;27358.726412736018;27365.315867103483;27740.890364385603;27879.860600696866;7349.228331025272;8322.70870519529;8477.618507167192;8582.227599516074;8644.91355086441;4193.895152621209;4237.766922819213;4265.1469706058915;4272.145570885836;4276.102018576072;4293.506246781598;4814.109389762905;4826.734529600835;4874.147024270738;4912.4350699669285;865.2429124516049;916.7478943290515;1097.8847221041835;1105.867295924494;1109.5839060352369;580.3702982264206;670.1154476091609;670.4837275297994;683.1597135523235;703.033329172388;11057.723556911142;11546.62667497674;12398.876011239952;13120.60638180856;13300.020944915004;649.2339039932924;667.6361383046154;675.392147067643;701.242657929435;702.1924786495571;5432.899892679746;6023.548233882389;6033.155358249848;6046.046643489139;6065.423784740443;156506;157069;161976;167812;168750;116957;125218;125577;137314;137965;86157;107953.46023269833;109150.45424772984;110938.44530777456;112758;12307.384630768605;12576.559820406243;12763.554405025403;12839.486420543073;13282.535111271445;44522.77738611286;48048;50571.49428505815;50607.49392506028;50884.74557627188;64742.02886956605;89347;90281.54859225925;90404;92544.14911701594;18232.544186395447;18335.81664183341;18381.356652517097;18430.89414635126;18469.445916622524;14574.635634109232;16451.341946322493;18763.530911727317;19392.4182274532;19571.804281956996;18012.81987180164;18422.90788546049;18574.25702971866;18816.905915470335;18897.244110235435;17936.372226971995;18514.629707405507;18554.165062572487;18573.349932752288;18648.34730784416;17659.205335759663;18101.275948961895;18392.816071839112;18458.538536536693;18647.160877760805;12430.81353779676;12708.745825083632;12820.871791281968;13318;13483.98870084728;14069.296535172996;14077.859221407654;14088.929555352157;14198.858011419754;14584.052036617586 16:04:18 INFO - PID 1655 | __end_tp_report 16:04:18 INFO - PID 1655 | __start_cc_report 16:04:18 INFO - PID 1655 | _x_x_mozilla_cycle_collect,384 16:04:18 INFO - PID 1655 | __end_cc_report 16:04:18 INFO - PID 1655 | __startTimestamp1497395058543__endTimestamp 16:04:18 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | ------- Summary: start ------- 16:04:18 INFO - PID 1655 | Number of tests: 6 16:04:18 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | [#0] dojo.html Cycles:165 Average:6464.65 Median:6696.53 stddev:786.29 (11.7%) stddev-sans-first:757.99 16:04:18 INFO - PID 1655 | Values: 3690.5 3691.6 3742.8 3823.7 3827.6 6277.9 6304.5 6316.7 6412.1 6466.5 6381.7 6401.6 6433.5 6435.0 6440.2 5535.4 5611.7 5673.9 5768.0 5823.9 5701.0 5732.4 5851.1 5856.2 5870.5 5509.6 5552.4 5610.4 5662.9 5698.7 5584.2 5635.5 5698.6 5703.9 5751.5 5622.1 5708.3 5720.5 5738.3 5744.3 6332.6 6377.9 6381.9 6415.5 6425.0 7014.4 7015.8 7029.9 7042.1 7068.8 6777.5 6879.3 6984.1 7086.5 7106.3 6535.1 6564.0 6572.5 6627.2 6634.2 7094.7 7310.1 7311.0 7321.3 7323.5 6645.5 6724.7 6744.4 6755.0 6764.8 7030.1 7098.9 7115.0 7126.7 7136.7 5716.9 6945.9 6984.3 6985.1 6991.3 6740.4 7009.3 7027.1 7037.0 7038.1 5470.7 5496.4 5515.6 5523.2 5523.9 4395.6 4896.1 4960.3 5067.5 5067.9 6083.0 6562.5 6688.8 6696.5 6715.2 7172.0 7208.0 7212.7 7218.4 7232.8 6425.0 6662.4 6673.5 6692.9 6715.4 6133.9 6140.9 6150.4 6162.0 6187.1 7271.7 7341.8 7369.9 7387.0 7398.7 7140.4 7184.2 7186.3 7217.0 7218.5 7284.8 7319.9 7377.5 7393.7 7396.0 6669.7 6995.1 7049.0 7054.3 7075.3 6934.2 6952.6 7024.9 7156.0 7157.9 6049.0 6509.7 6804.2 6878.3 6905.3 6894.6 6927.5 6934.1 6942.5 6960.5 6478.6 6584.2 6642.7 6728.2 6773.4 6757.7 6774.1 6789.6 6797.5 6802.9 6682.6 6693.2 6730.9 6766.5 6793.0 16:04:18 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | [#1] ext.html Cycles:180 Average:47920.22 Median:20434.56 stddev:86022.68 (421.0%) stddev-sans-first:86191.30 16:04:18 INFO - PID 1655 | Values: 816.5 869.4 888.9 899.1 1272.4 37476.4 41262.8 41306.8 41347.0 41461.6 41880.7 42352.0 42379.6 42447.6 42489.8 8251.3 8296.0 8384.6 8384.8 8393.7 8477.4 8706.7 8709.1 8714.2 8719.5 7432.0 8811.6 8812.2 8813.2 8817.0 8710.2 8717.3 8780.5 8783.3 8803.6 7576.7 8394.0 8530.7 8581.9 8603.6 38921.8 39091.4 39221.6 39273.0 39506.0 3697.1 3702.7 3732.9 3735.8 3750.1 32300.4 32300.5 32356.7 32381.5 32497.9 3216.3 3251.9 3261.8 3286.1 3322.2 266188.0 269750.0 270728.0 328584.0 328973.0 37725.2 50165.2 50549.5 50561.2 50580.0 52780.2 52893.7 52913.2 53181.7 53195.7 16300.5 16433.9 16571.9 16584.1 16626.2 13552.6 13584.6 13613.3 13651.8 13693.2 14146.9 14172.9 14267.0 14283.4 14297.6 6020.8 6032.2 6046.3 6058.6 6079.0 954.9 972.3 982.2 1020.5 1044.2 21915.5 23276.3 23584.2 23650.0 24884.8 991.5 1025.8 1054.2 1106.3 1173.7 13982.4 15604.8 16162.7 16171.4 16293.0 371861.0 373278.0 375293.0 375477.0 377095.0 53988.0 54092.5 54126.0 54480.7 54505.7 1420.2 1512.6 1569.5 1581.3 1584.9 28410.9 30081.2 30475.8 30525.5 30737.7 38555.2 38587.0 38601.4 38626.2 38705.0 307081.0 307100.0 307473.0 307966.0 308195.0 30271.0 30730.8 30731.0 31408.7 35739.9 42118.9 42745.4 43135.8 43205.8 45393.0 28979.4 30118.7 30172.8 31121.4 31147.2 30454.1 30547.8 30574.8 30968.4 31079.4 36233.6 36692.0 37166.4 37791.1 37923.4 29482.7 30042.5 30631.0 31118.4 31134.0 22958.0 24485.3 24832.6 24832.8 24884.8 16:04:18 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | [#2] jquery.html Cycles:180 Average:42868.13 Median:12423.30 stddev:105566.90 (849.7%) stddev-sans-first:105815.06 16:04:18 INFO - PID 1655 | Values: 475.8 542.2 546.1 549.5 550.5 24538.9 25234.9 25342.4 25348.9 25383.6 24995.5 27685.9 27756.6 27760.0 27797.9 6776.4 6883.9 6930.5 6937.4 6942.5 6916.6 6968.9 6982.8 6983.9 6986.9 6252.5 6785.2 6793.7 6826.0 6849.8 6810.5 6847.2 6849.4 6879.4 6880.2 6301.4 6305.7 6309.2 6316.8 6323.8 30254.1 32526.5 32583.8 32989.5 33312.2 2366.8 2426.1 2430.9 2432.9 2434.4 20601.1 21115.9 21118.4 21119.2 21131.8 2265.2 2301.8 2320.3 2321.9 2347.9 215454.0 219078.9 223641.0 223922.0 224389.0 14487.8 16061.9 16252.0 16291.5 17290.6 22167.1 22314.6 22359.8 22466.6 22478.6 10085.1 10876.6 11008.3 11061.4 11123.9 9661.6 10192.4 10238.3 10370.2 10423.0 9679.3 9765.1 9820.1 9834.1 9851.6 2732.7 2754.4 2773.3 2773.3 2807.4 885.8 966.3 975.7 983.4 1047.0 15683.0 20887.2 21603.8 21931.6 22581.4 1000.3 1014.6 1026.8 1057.2 1068.4 10929.1 11275.8 11329.5 11400.5 11603.3 573688.0 597369.0 602616.0 607587.0 637882.0 159092.0 174499.0 174768.1 177386.0 178397.0 1201.6 1219.2 1273.1 1273.5 1290.7 22529.2 24271.8 24286.3 24568.5 24614.5 71523.3 74647.3 75234.6 75275.0 75385.6 88463.1 92773.1 93688.0 97364.5 97558.5 11972.8 12145.6 12263.5 12284.2 12305.4 14205.8 14450.3 15702.2 18692.3 18774.0 15231.3 15251.5 15270.3 15271.8 15731.6 13294.4 13325.9 13576.4 14273.5 14368.9 15482.8 15851.4 15970.4 16006.4 16094.2 11911.4 12101.0 12116.3 12117.6 12176.9 15283.8 15400.0 15410.2 15440.0 15495.3 16:04:18 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | [#3] mootools.html Cycles:180 Average:8715.78 Median:2910.60 stddev:5844.06 (200.8%) stddev-sans-first:5834.74 16:04:18 INFO - PID 1655 | Values: 1420.7 1455.8 1463.3 1478.9 1499.9 12305.0 13994.3 14579.9 14595.3 14611.9 13979.2 14745.6 14816.9 14819.5 14859.2 2902.3 2951.9 2978.5 2978.9 2986.5 2683.8 2931.5 2934.4 2939.5 2945.3 2704.9 2933.8 2945.9 2952.6 2956.0 2879.6 2898.3 2907.2 2909.4 2911.8 2850.9 2898.6 2944.6 2945.1 2947.1 14288.5 14351.9 14435.4 14441.9 14492.6 7889.9 8110.1 8119.7 8149.8 8200.5 4920.0 5131.5 5240.7 5261.8 5272.9 1475.3 1490.1 1506.3 1525.7 1531.2 21493.6 21600.4 21609.8 21646.5 21669.8 6066.1 6255.9 6949.8 6983.8 7006.1 14046.5 14991.8 15007.4 15033.0 15042.9 7755.5 7969.5 7993.8 8017.0 8022.8 6022.6 6233.1 6631.7 7476.4 7502.9 5325.7 5470.1 5552.9 5643.5 5677.8 1418.2 1604.3 1606.3 1642.1 1643.0 822.4 856.6 864.7 868.1 885.0 6003.4 7224.3 7506.4 7565.4 7615.8 693.3 701.5 713.7 733.4 737.9 5014.6 5085.8 5128.9 5248.3 5336.2 18840.6 19740.2 19770.0 19778.4 19785.8 17664.9 18244.9 18856.0 19572.8 19614.1 9819.7 10199.8 10262.7 10333.6 10359.2 7865.6 8006.0 8090.6 8097.6 8548.5 17491.7 17725.4 17729.6 18009.7 18025.5 16815.5 17611.1 17931.6 18396.7 18402.6 9371.1 9661.7 10464.1 10549.8 10550.6 12271.6 12991.2 13449.5 13715.5 13725.8 10498.7 10730.6 10987.4 11014.9 11015.8 10255.8 10297.6 10445.2 10484.3 10849.9 9669.0 9723.5 9747.6 9753.3 9766.7 6865.9 7028.8 7085.7 7159.2 7297.4 6957.5 7208.5 7217.4 7467.6 7473.6 16:04:18 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | [#4] prototype.html Cycles:180 Average:5574.86 Median:6150.32 stddev:1729.68 (28.1%) stddev-sans-first:1691.24 16:04:18 INFO - PID 1655 | Values: 451.1 464.1 475.9 486.3 494.3 5418.6 5595.9 6511.4 6550.8 6595.0 5634.5 5758.3 6270.2 6367.2 6371.5 3889.6 4082.7 4722.2 4833.6 4853.3 4173.0 4196.8 4834.4 4838.7 4861.5 4557.5 4778.9 4787.3 4801.9 4802.4 4790.7 4795.8 4795.8 4797.7 4809.2 3655.7 4212.4 4224.3 4237.0 4237.7 5102.4 5642.4 6243.3 6309.2 6317.3 5664.2 5689.4 5710.6 5716.6 5739.5 6923.6 6935.7 6951.6 6954.8 6980.0 4338.9 4490.7 4855.4 4855.6 4885.5 5605.7 6554.9 7290.9 7357.4 7459.7 4928.6 4946.7 4953.8 4960.7 4973.4 5020.8 5077.6 5088.9 5097.1 5100.2 5667.4 5697.2 5700.3 5713.2 5744.3 4995.6 6917.3 6993.7 6996.9 7123.7 1254.0 1713.5 1730.5 1739.0 1741.3 949.4 1032.9 1045.5 1171.7 1180.7 6048.5 6066.8 6255.8 6279.4 6595.4 5224.6 6475.8 6951.0 7156.6 7161.6 6061.9 6519.3 6547.7 6568.9 6587.3 2975.5 3140.9 3696.6 3729.7 3745.0 6398.1 6946.4 7467.4 7481.8 7514.4 6632.6 7392.0 7396.2 7417.3 7435.7 6723.5 7425.7 7443.3 7456.5 7458.0 6879.3 7098.6 7150.5 7162.7 7203.2 6252.4 6362.3 6949.6 6988.2 7034.6 5871.4 7390.0 7396.7 7415.0 7438.8 4608.3 4930.6 4935.7 4943.2 4966.2 6551.9 6795.1 6872.6 7170.1 7172.6 5598.4 6288.3 6382.6 6829.7 6926.3 6836.8 6839.6 6850.7 6854.7 6864.6 6054.9 6158.7 6766.0 6798.5 6804.3 5833.2 6342.6 6799.3 6941.4 6973.3 6141.9 6677.6 6701.3 6717.2 6729.2 16:04:18 INFO - PID 1655 | 16:04:18 INFO - PID 1655 | [#5] yui.html Cycles:180 Average:27965.18 Median:18426.90 stddev:43699.80 (237.2%) stddev-sans-first:43775.01 16:04:18 INFO - PID 1655 | Values: 861.7 864.3 868.5 880.9 893.2 17987.3 18187.0 18322.6 18359.5 18362.7 17406.8 18326.6 19544.0 19579.0 19599.7 927.3 1030.2 1064.2 1067.7 1068.1 1063.4 1064.9 1066.5 1067.9 1075.7 944.9 1016.1 1130.3 1130.3 1133.2 1092.7 1102.0 1109.5 1119.8 1123.2 807.6 813.2 814.8 815.6 818.7 18388.0 18702.3 18878.6 18930.6 19134.8 17690.6 18371.7 18763.2 18824.3 18893.9 15276.8 15282.7 15284.8 15325.9 15372.2 3702.0 3796.7 3825.5 3843.3 3849.3 155054.0 157583.0 167221.3 171446.0 175136.0 14254.5 14499.9 14575.0 14585.9 14586.2 27211.6 27358.7 27365.3 27740.9 27879.9 7349.2 8322.7 8477.6 8582.2 8644.9 4193.9 4237.8 4265.1 4272.1 4276.1 4293.5 4814.1 4826.7 4874.1 4912.4 865.2 916.7 1097.9 1105.9 1109.6 580.4 670.1 670.5 683.2 703.0 11057.7 11546.6 12398.9 13120.6 13300.0 649.2 667.6 675.4 701.2 702.2 5432.9 6023.5 6033.2 6046.0 6065.4 156506.0 157069.0 161976.0 167812.0 168750.0 116957.0 125218.0 125577.0 137314.0 137965.0 86157.0 107953.5 109150.5 110938.4 112758.0 12307.4 12576.6 12763.6 12839.5 13282.5 44522.8 48048.0 50571.5 50607.5 50884.7 64742.0 89347.0 90281.5 90404.0 92544.1 18232.5 18335.8 18381.4 18430.9 18469.4 14574.6 16451.3 18763.5 19392.4 19571.8 18012.8 18422.9 18574.3 18816.9 18897.2 17936.4 18514.6 18554.2 18573.3 18648.3 17659.2 18101.3 18392.8 18458.5 18647.2 12430.8 12708.7 12820.9 13318.0 13484.0 14069.3 14077.9 14088.9 14198.9 14584.1 16:04:18 INFO - PID 1655 | -------- Summary: end -------- 16:04:18 INFO - PID 1655 | 16:04:19 INFO - TEST-INFO | 1655: exit 0 16:04:19 INFO - TEST-OK | dromaeo_css | took 1142463ms 16:04:19 INFO - TEST-START | kraken 16:04:19 INFO - operating with platform_type : mac_ 16:04:19 INFO - Initialising browser for kraken test... 16:04:19 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmp17v9fZ/profile 16:04:19 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 16:04:51 INFO - TEST-INFO | 1665: killed by SIGTERM 16:04:51 INFO - Browser initialized. 16:04:51 INFO - Running cycle 1/1 for kraken test... 16:04:51 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmp17v9fZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:04:53 INFO - PID 1668 | 2017-06-13 16:04:53.629 plugin-container[1669:10587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954f, name = 'com.apple.tsm.portname' 16:04:53 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:53 INFO - PID 1668 | 2017-06-13 16:04:53.629 plugin-container[1669:10587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 16:04:53 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:53 INFO - PID 1668 | 2017-06-13 16:04:53.629 plugin-container[1669:10587] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:05:03 INFO - PID 1668 | RSS: Main: 185012224 16:05:03 INFO - PID 1668 | 16:05:06 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 16:05:07 INFO - PID 1668 | RSS: Main: 198033408 16:05:07 INFO - PID 1668 | 16:05:10 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 16:05:10 INFO - PID 1668 | RSS: Main: 207769600 16:05:10 INFO - PID 1668 | 16:05:14 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 16:05:14 INFO - PID 1668 | RSS: Main: 212197376 16:05:14 INFO - PID 1668 | 16:05:16 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 16:05:17 INFO - PID 1668 | RSS: Main: 213016576 16:05:17 INFO - PID 1668 | 16:05:19 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:05:19 INFO - PID 1668 | RSS: Main: 215314432 16:05:19 INFO - PID 1668 | 16:05:22 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 16:05:23 INFO - PID 1668 | RSS: Main: 219201536 16:05:23 INFO - PID 1668 | 16:05:26 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 16:05:27 INFO - PID 1668 | RSS: Main: 219516928 16:05:27 INFO - PID 1668 | 16:05:29 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 16:05:30 INFO - PID 1668 | RSS: Main: 219279360 16:05:30 INFO - PID 1668 | 16:05:32 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:05:32 INFO - PID 1668 | RSS: Main: 219127808 16:05:32 INFO - PID 1668 | 16:05:34 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:05:35 INFO - PID 1668 | RSS: Main: 219103232 16:05:35 INFO - PID 1668 | 16:05:37 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:05:37 INFO - PID 1668 | RSS: Main: 219172864 16:05:37 INFO - PID 1668 | 16:05:40 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:05:41 INFO - PID 1668 | RSS: Main: 219111424 16:05:41 INFO - PID 1668 | 16:05:44 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:05:44 INFO - PID 1668 | RSS: Main: 219570176 16:05:44 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 16:05:46 INFO - PID 1668 | RSS: Main: 221130752 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | __start_tp_report 16:05:46 INFO - PID 1668 | _x_x_mozilla_page_load 16:05:46 INFO - PID 1668 | _x_x_mozilla_page_load_details 16:05:46 INFO - PID 1668 | |i|pagename|runs| 16:05:46 INFO - PID 1668 | |0;ai-astar;112;116;97;112;117;122;90;153;167;95 16:05:46 INFO - PID 1668 | |1;audio-beat-detection;127;106;145;132;111;156;140;148;169;133 16:05:46 INFO - PID 1668 | |2;audio-dft;199;170;205;193;176;208;223;273;277;197 16:05:46 INFO - PID 1668 | |3;audio-fft;58;104;73;158;84;87;76;58;123;56 16:05:46 INFO - PID 1668 | |4;audio-oscillator;69;79;118;72;59;65;126;100;63;114 16:05:46 INFO - PID 1668 | |5;imaging-gaussian-blur;91;90;90;89;91;88;89;90;91;89 16:05:46 INFO - PID 1668 | |6;imaging-darkroom;100;96;125;141;151;131;96;96;97;97 16:05:46 INFO - PID 1668 | |7;imaging-desaturate;93;134;91;92;91;89;93;91;94;89 16:05:46 INFO - PID 1668 | |8;json-parse-financial;50;46;47;51;51;50;50;50;49;54 16:05:46 INFO - PID 1668 | |9;json-stringify-tinderbox;52;61;48;55;56;51;64;70;75;78 16:05:46 INFO - PID 1668 | |10;stanford-crypto-aes;80;78;78;79;78;76;78;78;77;97 16:05:46 INFO - PID 1668 | |11;stanford-crypto-ccm;132;131;129;129;129;137;136;131;136;164 16:05:46 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;175;173;173;169;175;175;170;176;187;213 16:05:46 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;64;65;63;67;68;89;84;88;75;63 16:05:46 INFO - PID 1668 | __end_tp_report 16:05:46 INFO - PID 1668 | __start_cc_report 16:05:46 INFO - PID 1668 | _x_x_mozilla_cycle_collect,978 16:05:46 INFO - PID 1668 | __end_cc_report 16:05:46 INFO - PID 1668 | __startTimestamp1497395146631__endTimestamp 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | ------- Summary: start ------- 16:05:46 INFO - PID 1668 | Number of tests: 14 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:118.10 Median:160.00 stddev:24.66 (15.4%) stddev-sans-first:26.06 16:05:46 INFO - PID 1668 | Values: 112.0 116.0 97.0 112.0 117.0 122.0 90.0 153.0 167.0 95.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:142.50 stddev:19.31 (13.6%) stddev-sans-first:20.16 16:05:46 INFO - PID 1668 | Values: 127.0 106.0 145.0 132.0 111.0 156.0 140.0 148.0 169.0 133.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:212.10 Median:206.50 stddev:36.43 (17.6%) stddev-sans-first:38.33 16:05:46 INFO - PID 1668 | Values: 199.0 170.0 205.0 193.0 176.0 208.0 223.0 273.0 277.0 197.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:87.70 Median:65.50 stddev:32.64 (49.8%) stddev-sans-first:32.81 16:05:46 INFO - PID 1668 | Values: 58.0 104.0 73.0 158.0 84.0 87.0 76.0 58.0 123.0 56.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:64.00 stddev:25.46 (39.8%) stddev-sans-first:26.21 16:05:46 INFO - PID 1668 | Values: 69.0 79.0 118.0 72.0 59.0 65.0 126.0 100.0 63.0 114.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:90.00 stddev:1.03 (1.1%) stddev-sans-first:1.00 16:05:46 INFO - PID 1668 | Values: 91.0 90.0 90.0 89.0 91.0 88.0 89.0 90.0 91.0 89.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:113.00 Median:96.00 stddev:21.72 (22.6%) stddev-sans-first:22.52 16:05:46 INFO - PID 1668 | Values: 100.0 96.0 125.0 141.0 151.0 131.0 96.0 96.0 97.0 97.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:91.50 stddev:13.56 (14.8%) stddev-sans-first:14.34 16:05:46 INFO - PID 1668 | Values: 93.0 134.0 91.0 92.0 91.0 89.0 93.0 91.0 94.0 89.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:50.00 stddev:2.20 (4.4%) stddev-sans-first:2.33 16:05:46 INFO - PID 1668 | Values: 50.0 46.0 47.0 51.0 51.0 50.0 50.0 50.0 49.0 54.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:62.50 stddev:10.47 (16.7%) stddev-sans-first:10.58 16:05:46 INFO - PID 1668 | Values: 52.0 61.0 48.0 55.0 56.0 51.0 64.0 70.0 75.0 78.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:78.00 stddev:6.10 (7.8%) stddev-sans-first:6.47 16:05:46 INFO - PID 1668 | Values: 80.0 78.0 78.0 79.0 78.0 76.0 78.0 78.0 77.0 97.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:134.00 stddev:10.51 (7.8%) stddev-sans-first:11.08 16:05:46 INFO - PID 1668 | Values: 132.0 131.0 129.0 129.0 129.0 137.0 136.0 131.0 136.0 164.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:175.00 stddev:13.03 (7.4%) stddev-sans-first:13.76 16:05:46 INFO - PID 1668 | Values: 175.0 173.0 173.0 169.0 175.0 175.0 170.0 176.0 187.0 213.0 16:05:46 INFO - PID 1668 | 16:05:46 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:71.50 stddev:10.60 (14.8%) stddev-sans-first:10.77 16:05:46 INFO - PID 1668 | Values: 64.0 65.0 63.0 67.0 68.0 89.0 84.0 88.0 75.0 63.0 16:05:46 INFO - PID 1668 | -------- Summary: end -------- 16:05:46 INFO - PID 1668 | 16:05:47 INFO - TEST-INFO | 1668: exit 0 16:05:47 INFO - TEST-OK | kraken | took 88332ms 16:05:47 INFO - SUITE-END | took 1230s 16:05:47 INFO - Completed test suite (00:20:31) 16:05:47 INFO - javascript benchmark 16:05:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8729.536643759722, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3690.501782259394, 3691.649293317137, 3742.8128593570323, 3823.655870971612, 3827.559830619478, 6277.874442511153, 6304.464120549759, 6316.652584107872, 6412.102305677209, 6466.547341686084, 6381.680915954207, 6401.551891367594, 6433.549651524383, 6435.034744788272, 6440.227172739266, 5535.424029585876, 5611.663300201981, 5673.886522269573, 5767.971160144193, 5823.854403639901, 5700.97382471155, 5732.398098199692, 5851.063829787235, 5856.180134781134, 5870.530357571384, 5509.559235261169, 5552.3892371839465, 5610.382857885629, 5662.943370566283, 5698.7435565399655, 5584.246126772874, 5635.520980716644, 5698.601097923147, 5703.914441283384, 5751.539876809887, 5622.128570071604, 5708.257926469532, 5720.48515633595, 5738.340090889517, 5744.310682718101, 6332.556721029529, 6377.904331434986, 6381.866778157695, 6415.454686351665, 6425.0, 7014.438844892396, 7015.824604384931, 7029.929700702979, 7042.119735033121, 7068.823279418004, 6777.457803375718, 6879.346462086094, 6984.126984126984, 7086.503944723871, 7106.324899134625, 6535.117759102555, 6564.0, 6572.474202063823, 6627.204735431779, 6634.23706273775, 7094.693930438588, 7310.086239220098, 7311.0495635568095, 7321.267873212636, 7323.450741194431, 6645.534812563074, 6724.731010759613, 6744.393004629607, 6754.966225168941, 6764.763233286812, 7030.050943122716, 7098.929010709827, 7114.964425177945, 7126.679299431538, 7136.7145314186855, 5716.914246286309, 6945.89581156283, 6984.301569842975, 6985.091938048021, 6991.30086991307, 6740.359665831738, 7009.334113259232, 7027.051348068047, 7036.964815175891, 7038.120234970629, 5470.671759694431, 5496.395396506381, 5515.61390702647, 5523.199136125239, 5523.944760552424, 4395.648348132142, 4896.118698634209, 4960.280759289883, 5067.543921047123, 5067.87330316745, 6082.9695851520455, 6562.475001999828, 6688.765893193812, 6696.531242813054, 6715.1941766988275, 7171.964140179266, 7208.026916366328, 7212.675429605681, 7218.3503484686635, 7232.783016509513, 6425.0, 6662.433693136138, 6673.4661227101715, 6692.933070669328, 6715.4291885190305, 6133.938660613337, 6140.9030504681, 6150.446459818638, 6162.0, 6187.102870083902, 7271.709131634675, 7341.816454588571, 7369.926300737031, 7386.96306518464, 7398.667059982421, 7140.428765698835, 7184.1738200105565, 7186.281371862772, 7217.0, 7218.530795498275, 7284.817879553054, 7319.938608901786, 7377.5204611700065, 7393.667284972082, 7395.963020184865, 6669.699863506251, 6995.090638217, 7048.964755176191, 7054.259302773135, 7075.327843854826, 6934.202566704894, 6952.617606031515, 7024.8595028098125, 7156.0, 7157.856842863218, 6049.001914684028, 6509.707063182073, 6804.183497980274, 6878.312168783082, 6905.309469053054, 6894.551854129466, 6927.549709268881, 6934.06390137325, 6942.548734332252, 6960.547564408297, 6478.643674597943, 6584.209894812653, 6642.734290628321, 6728.192616886005, 6773.390394864289, 6757.729690812313, 6774.051632771515, 6789.592624442549, 6797.524173307821, 6802.863942721019, 6682.599044057372, 6693.230278518033, 6730.8653826924165, 6766.458683305225, 6793.015012823212], "value": 6410.53394165589, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.4896939412866, 869.3783944479695, 888.942218755781, 899.0919171636644, 1272.3638180909545, 37476.43785343222, 41262.79368603157, 41306.76079717613, 41346.99999999996, 41461.58538414615, 41880.74357769264, 42352.0, 42379.57620423795, 42447.57552424475, 42489.787551062276, 8251.29863961564, 8296.045954715224, 8384.580770961458, 8384.83230335393, 8393.748187554384, 8477.406581539295, 8706.651733930636, 8709.129087091304, 8714.172153645393, 8719.520426376546, 7431.962840185818, 8811.647534098629, 8812.162844529792, 8813.162749538815, 8816.986046604658, 8710.21608055275, 8717.346199035066, 8780.47317160966, 8783.297336213087, 8803.64785408583, 7576.659050342748, 8393.958030209871, 8530.65877364911, 8581.914180858173, 8603.5698215089, 38921.80539097286, 39091.41362879531, 39221.60778392208, 39273.0, 39506.024698764944, 3697.0757310672334, 3702.6852717518773, 3732.869349572779, 3735.794531300777, 3750.1187221003042, 32300.384980750867, 32300.546475408428, 32356.676433235603, 32381.51427728586, 32497.86257480977, 3216.3245718399166, 3251.869925202989, 3261.804291742479, 3286.0798976286437, 3322.18606441421, 266188.0, 269750.0, 270728.0, 328584.0, 328973.0, 37725.245495090494, 50165.24752128721, 50549.49450505521, 50561.24158137631, 50580.0, 52780.20829687558, 52893.73553132287, 52913.206301905506, 53181.73409133007, 53195.73402132965, 16300.510984670478, 16433.91783041077, 16571.91714041446, 16584.08787516674, 16626.168691565374, 13552.593422197153, 13584.592462226148, 13613.251271179983, 13651.795223071662, 13693.178409295475, 14146.938979576573, 14172.85827141736, 14267.001309908206, 14283.428662853577, 14297.64255893611, 6020.849478763066, 6032.215811944419, 6046.274447066292, 6058.606190597597, 6078.969605151946, 954.8519979403204, 972.3388096094724, 982.1700662939804, 1020.4642562654666, 1044.2150486305932, 21915.452113697283, 23276.301710948697, 23584.174553890876, 23650.0, 24884.760371566645, 991.504247876062, 1025.7692019295748, 1054.1988089052375, 1106.336198281025, 1173.677238759348, 13982.370793314325, 15604.765928511082, 16162.676746465006, 16171.35314587427, 16293.0, 371861.0, 373278.0, 375293.0, 375477.0, 377095.0, 53988.0, 54092.45907540874, 54126.0, 54480.72759636256, 54505.727471363185, 1420.2330741399735, 1512.6445285357931, 1569.4585368048006, 1581.320032386062, 1584.9286782094835, 28410.85794571014, 30081.247968800955, 30475.847620761753, 30525.542116867822, 30737.692623073483, 38555.22889542137, 38587.0, 38601.42097868591, 38626.2274754509, 38705.0, 307081.0, 307100.0, 307473.0, 307966.0, 308195.0, 30271.0, 30730.84634576813, 30731.0, 31408.68591314149, 35739.92780216598, 42118.94702632459, 42745.35881961711, 43135.784321078194, 43205.783971079945, 45393.0, 28979.420411592073, 30118.698813012466, 30172.84913575506, 31121.377572448877, 31147.221319467193, 30454.086377408712, 30547.847260764443, 30574.847125765118, 30968.360498561877, 31079.37841243117, 36233.63766362303, 36692.0, 37166.44250336193, 37791.05522361963, 37923.431148533346, 29482.705172947997, 30042.549361759153, 30631.0, 31118.377632446773, 31134.0, 22958.0, 24485.26544203677, 24832.627510586994, 24832.751672483766, 24884.751152488967], "value": 18245.33749103086, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.7573637444903, 542.2110828744178, 546.0907588864541, 549.5164255455198, 550.5017958747333, 24538.87730561347, 25234.86443110062, 25342.36644083899, 25348.873255633694, 25383.619245711307, 24995.50008999819, 27685.86157069217, 27756.583651245197, 27760.0, 27797.86101069492, 6776.42400395967, 6883.896741548856, 6930.549514281581, 6937.410320122795, 6942.479314051441, 6916.585004899722, 6968.860622787542, 6982.755603553876, 6983.930160698379, 6986.895196572055, 6252.531060170505, 6785.1857777066825, 6793.694283757218, 6826.0, 6849.7602583909575, 6810.523263371566, 6847.246802851682, 6849.383555479981, 6879.449644028466, 6880.174379074518, 6301.432871041582, 6305.716242769086, 6309.211348581427, 6316.810495685137, 6323.7786677466065, 30254.092377228717, 32526.512102318484, 32583.837080814443, 32989.50515742266, 33312.16719582005, 2366.8461549999197, 2426.1265944260053, 2430.86630235337, 2432.902683892642, 2434.379233295516, 20601.072951717208, 21115.9442027898, 21118.3664490064, 21119.15523379063, 21131.837748923805, 2265.161890100657, 2301.79283864453, 2320.3271051395077, 2321.94195145121, 2347.8826058696995, 215454.0, 219078.90460547755, 223640.99999999837, 223922.0, 224389.0, 14487.78268325976, 16061.919690401706, 16252.0, 16291.511254662379, 17290.567735806453, 22167.113315467202, 22314.553708926054, 22359.776402236093, 22466.55066898653, 22478.55042899133, 10085.092341689284, 10876.619318323821, 11008.284461509977, 11061.391623460626, 11123.944380278208, 9661.613535458644, 10192.388456692623, 10238.33450825694, 10370.170386369073, 10423.0, 9679.274054445805, 9765.144622521699, 9820.116189542976, 9834.114929656222, 9851.605935762489, 2732.699403065661, 2754.352727109128, 2773.3066733316673, 2773.334399744047, 2807.354308509054, 885.7652722028668, 966.3187452845727, 975.687779910436, 983.3706427886159, 1046.9790604188026, 15683.0, 20887.164513419597, 21603.78396216049, 21931.561368772534, 22581.4354641132, 1000.3047881722279, 1014.6195176808696, 1026.8440698015445, 1057.2123767793043, 1068.369661899483, 10929.071028962628, 11275.830862537068, 11329.490172942238, 11400.543978240778, 11603.303801771921, 573688.0, 597369.0, 602616.0, 607587.0, 637882.0, 159092.0, 174499.0, 174768.1261593684, 177386.0, 178397.0, 1201.591458903977, 1219.2196993923897, 1273.1342686972762, 1273.5287943460978, 1290.6708789258867, 22529.21147759886, 24271.75728242695, 24286.271411857673, 24568.508629826945, 24614.507709845344, 71523.28476715166, 74647.25352746402, 75234.62382688052, 75275.0, 75385.62307188429, 88463.1153688455, 92773.14453711023, 93688.0, 97364.51317743366, 97558.51220744135, 11972.820407694066, 12145.574904878287, 12263.509459621515, 12284.201526900722, 12305.446254918372, 14205.786913196516, 14450.277486125862, 15702.21488925572, 18692.34576789806, 18774.0, 15231.314590843216, 15251.542453726406, 15270.312835922188, 15271.77092343638, 15731.606709832346, 13294.401751920994, 13325.866741332462, 13576.389062492013, 14273.500427484989, 14368.856311436752, 15482.84517154814, 15851.445199418426, 15970.441034563735, 16006.439774607832, 16094.195290235675, 11911.40442977865, 12101.0, 12116.33360165164, 12117.575884843987, 12176.878231217574, 15283.847161528687, 15400.0, 15410.229488525305, 15439.99640023395, 15495.30271137779], "value": 12484.092489705956, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.7087547052856, 1455.8325792533863, 1463.3122432456748, 1478.903871248369, 1499.8575135362137, 12305.0, 13994.300284985748, 14579.854201457982, 14595.343209555569, 14611.92694036531, 13979.23114228717, 14745.631359216024, 14816.851831481681, 14819.48131815384, 14859.182744949023, 2902.2889392098964, 2951.9262018449494, 2978.538326559387, 2978.8510574471297, 2986.462436761382, 2683.758461738443, 2931.5016447204025, 2934.4277865816175, 2939.4855900217485, 2945.26368407898, 2704.9323766905786, 2933.779966502521, 2945.9263518412, 2952.5571164325306, 2955.970440295591, 2879.58246054322, 2898.318895059659, 2907.2295841601995, 2909.3744844858247, 2911.8252904825777, 2850.9429811403656, 2898.6376702912135, 2944.6466424029254, 2945.072302224792, 2947.0864032150103, 14288.499902503363, 14351.856481435156, 14435.35040923161, 14441.927790361085, 14492.565223043326, 7889.8816517752275, 8110.107888132301, 8119.675212991473, 8149.837003259901, 8200.466969646954, 4920.0, 5131.461196574343, 5240.68555886648, 5261.789528418859, 5272.868178295535, 1475.2844870237925, 1490.1059364381392, 1506.314626844783, 1525.7253694335016, 1531.2343828085957, 21493.57012859734, 21600.351989440343, 21609.783902160936, 21646.458838529004, 21669.783302166936, 6066.149824303541, 6255.906161407583, 6949.756758513528, 6983.755568555076, 7006.068208857937, 14046.508372206923, 14991.775123373158, 15007.399704011717, 15033.022853514487, 15042.924785376223, 7755.495892766952, 7969.481983671159, 7993.760187194393, 8017.0, 8022.759317220492, 6022.608530445507, 6233.1273621693, 6631.6684165792485, 7476.364509016795, 7502.924970750332, 5325.707086110224, 5470.070088084955, 5552.888942221133, 5643.52030077448, 5677.772889084473, 1418.1987177244823, 1604.310146636934, 1606.2530923120805, 1642.0968467342914, 1642.991785041067, 822.43663090783, 856.6273670953155, 864.6843901975734, 868.1318681318681, 884.9867251991225, 6003.3996600339615, 7224.313690199423, 7506.399488040944, 7565.356944659656, 7615.771526854092, 693.3090987450161, 701.5475018612966, 713.653877869233, 733.3546479098466, 737.8710572823588, 5014.648974571818, 5085.847424577269, 5128.9230661540105, 5248.265242866002, 5336.199570064521, 18840.623187536446, 19740.210391584464, 19770.0, 19778.406647800588, 19785.823559408454, 17664.940103593824, 18244.90877545604, 18856.0, 19572.804271957382, 19614.117364718622, 9819.705408837746, 10199.847002294971, 10262.743431414274, 10333.586656533806, 10359.223058270509, 7865.60671966388, 8006.0, 8090.6359213836695, 8097.554634495159, 8548.487090774574, 17491.650166996333, 17725.379611713528, 17729.645407092044, 18009.729854051937, 18025.549361266072, 16815.495535133967, 17611.119444027492, 17931.5517112068, 18396.724049139542, 18402.63194736071, 9371.062893710574, 9661.71014869555, 10464.058234758908, 10549.841752373566, 10550.630727924487, 12271.57049503263, 12991.155574887602, 13449.46202151942, 13715.45138194501, 13725.794113088512, 10498.6588210614, 10730.624428144978, 10987.39569323695, 11014.889851101707, 11015.83476247873, 10255.794884102426, 10297.588096476356, 10445.216608754527, 10484.266101373136, 10849.891501084889, 9668.951655241679, 9723.46520941355, 9747.561359738687, 9753.317267791186, 9766.658166964406, 6865.862682746218, 7028.769965256162, 7085.716571337089, 7159.248278930637, 7297.379722723628, 6957.512974091765, 7208.5314454562385, 7217.422606191597, 7467.58928258951, 7473.626318683935], "value": 6385.007201525688, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.1383257977264, 464.14365495660513, 475.9143354196245, 486.2803051483806, 494.3425244424913, 5418.647787893789, 5595.916061259084, 6511.446527045201, 6550.836229094275, 6594.967025164873, 5634.521065709409, 5758.337791154018, 6270.24757029156, 6367.153525477617, 6371.52751750296, 3889.591592882749, 4082.7142100053006, 4722.197226471508, 4833.613310935126, 4853.320535125085, 4172.97913510432, 4196.769177695225, 4834.395700537433, 4838.661293709441, 4861.465238823726, 4557.498675145731, 4778.880527986811, 4787.305840653104, 4801.927971080437, 4802.351682522867, 4790.688605240648, 4795.769966965343, 4795.808167673289, 4797.736124513151, 4809.2305231162845, 3655.7258205634416, 4212.410262563244, 4224.281872081753, 4237.0, 4237.724547904407, 5102.387713474407, 5642.379338272786, 6243.313235544087, 6309.242890853127, 6317.272809042687, 5664.235328230678, 5689.435405263544, 5710.628809127394, 5716.628419152742, 5739.454751798572, 6923.619200943946, 6935.653217339112, 6951.617661028641, 6954.756583519553, 6980.022796808451, 4338.913221735579, 4490.708103973231, 4855.417349917997, 4855.63582731293, 4885.462599114095, 5605.691686957216, 6554.9184384576965, 7290.869915163158, 7357.41140708742, 7459.738909138208, 4928.560362620526, 4946.703197808143, 4953.752312384367, 4960.727160006234, 4973.378327709036, 5020.774065167003, 5077.644564880497, 5088.872778180501, 5097.133487307166, 5100.158473851773, 5667.3847953333925, 5697.230873832063, 5700.25896633435, 5713.171590119409, 5744.281964754406, 4995.550400463976, 6917.273686262971, 6993.70616435961, 6996.8950465743055, 7123.7230873798635, 1253.9811902821466, 1713.5373449168655, 1730.4771800815454, 1739.0348356662173, 1741.329588108562, 949.3829011142674, 1032.8863824979246, 1045.5451878432755, 1171.701216189866, 1180.7461395799944, 6048.4858787003595, 6066.817995460143, 6255.811395834777, 6279.425435571394, 6595.406413422815, 5224.608154388436, 6475.7733479328, 6950.965245173742, 7156.570605763567, 7161.606111663908, 6061.87855246772, 6519.3154718754795, 6547.672616369163, 6568.86862262752, 6587.308332625101, 2975.4941659917868, 3140.9214769630485, 3696.5933747287777, 3729.68297694699, 3744.981275093607, 6398.12084561949, 6946.409555187865, 7467.357181420079, 7481.812954676177, 7514.436417268617, 6632.568883022588, 7392.039034925425, 7396.2279237588145, 7417.329761286041, 7435.665395057235, 6723.462123030145, 7425.7029718810645, 7443.292887175709, 7456.4780465367985, 7457.962710186414, 6879.277675843964, 7098.574085554883, 7150.463715221275, 7162.677679504539, 7203.226757698219, 6252.374762523711, 6362.331955144643, 6949.61777102244, 6988.161420629353, 7034.577925324497, 5871.412858714265, 7390.0, 7396.667149978156, 7415.0, 7438.776836694908, 4608.331791890224, 4930.605551555938, 4935.728534930469, 4943.159662857395, 4966.155753521838, 6551.868962620816, 6795.082663840319, 6872.62200578973, 7170.139583250043, 7172.641367931687, 5598.396745513356, 6288.27684816252, 6382.5532212744665, 6829.726810927706, 6926.307369263033, 6836.769381511375, 6839.623820689711, 6850.691718872762, 6854.657267136723, 6864.553804002923, 6054.939450605438, 6158.722857471335, 6766.0, 6798.52410331272, 6804.251532331538, 5833.241678581757, 6342.587731797418, 6799.286074962058, 6941.409980151744, 6973.267806880204, 6141.9078713818435, 6677.565958212701, 6701.296363881722, 6717.16035495563, 6729.158855143107], "value": 5052.207713055702, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.6553378648543, 864.2955990867447, 868.5092922498787, 880.9383343165977, 893.209509584018, 17987.280508779648, 18186.99971501567, 18322.63354732908, 18359.54101147472, 18362.724559131624, 17406.825931740677, 18326.633467330645, 19544.0, 19579.0, 19599.706004409945, 927.2998885841181, 1030.1501261459307, 1064.2231171244996, 1067.738404090995, 1068.0814499530397, 1063.415121683075, 1064.930779499334, 1066.4934156275747, 1067.941263230522, 1075.671920064384, 944.8866136063648, 1016.11090295991, 1130.2766229613055, 1130.3387518301804, 1133.184107442644, 1092.6667366453228, 1102.0412241350077, 1109.4896347680076, 1119.8488204092507, 1123.2305870478667, 807.563915485637, 813.190875079294, 814.7840822182127, 815.6003558256411, 818.7257268814955, 18388.0, 18702.345417910445, 18878.62242755137, 18930.621387572308, 19134.80865191344, 17690.646187076316, 18371.72442413365, 18763.249470021183, 18824.341148059888, 18893.9055304724, 15276.770848437282, 15282.694346113016, 15284.770728439082, 15325.927185097047, 15372.154531500762, 3701.9629803701887, 3796.658300752918, 3825.540935087807, 3843.3466310727244, 3849.326367885637, 155054.0, 157583.0, 167221.32778672178, 171446.0, 175136.0, 14254.501092461715, 14499.92750036243, 14575.0, 14585.906057045764, 14586.197759123137, 27211.591826122625, 27358.726412736018, 27365.315867103483, 27740.890364385603, 27879.860600696866, 7349.228331025272, 8322.70870519529, 8477.618507167192, 8582.227599516074, 8644.91355086441, 4193.895152621209, 4237.766922819213, 4265.1469706058915, 4272.145570885836, 4276.102018576072, 4293.506246781598, 4814.109389762905, 4826.734529600835, 4874.147024270738, 4912.4350699669285, 865.2429124516049, 916.7478943290515, 1097.8847221041835, 1105.867295924494, 1109.5839060352369, 580.3702982264206, 670.1154476091609, 670.4837275297994, 683.1597135523235, 703.033329172388, 11057.723556911142, 11546.62667497674, 12398.876011239952, 13120.60638180856, 13300.020944915004, 649.2339039932924, 667.6361383046154, 675.392147067643, 701.242657929435, 702.1924786495571, 5432.899892679746, 6023.548233882389, 6033.155358249848, 6046.046643489139, 6065.423784740443, 156506.0, 157069.0, 161976.0, 167812.0, 168750.0, 116957.0, 125218.0, 125577.0, 137314.0, 137965.0, 86157.0, 107953.46023269833, 109150.45424772984, 110938.44530777456, 112758.0, 12307.384630768605, 12576.559820406243, 12763.554405025403, 12839.486420543073, 13282.535111271445, 44522.77738611286, 48048.0, 50571.49428505815, 50607.49392506028, 50884.74557627188, 64742.02886956605, 89347.0, 90281.54859225925, 90404.0, 92544.14911701594, 18232.544186395447, 18335.81664183341, 18381.356652517097, 18430.89414635126, 18469.445916622524, 14574.635634109232, 16451.341946322493, 18763.530911727317, 19392.4182274532, 19571.804281956996, 18012.81987180164, 18422.90788546049, 18574.25702971866, 18816.905915470335, 18897.244110235435, 17936.372226971995, 18514.629707405507, 18554.165062572487, 18573.349932752288, 18648.34730784416, 17659.205335759663, 18101.275948961895, 18392.816071839112, 18458.538536536693, 18647.160877760805, 12430.81353779676, 12708.745825083632, 12820.871791281968, 13318.0, 13483.98870084728, 14069.296535172996, 14077.859221407654, 14088.929555352157, 14198.858011419754, 14584.052036617586], "value": 9394.4950814754, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 97.0, 112.0, 117.0, 122.0, 90.0, 153.0, 167.0, 95.0], "value": 118.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 106.0, 145.0, 132.0, 111.0, 156.0, 140.0, 148.0, 169.0, 133.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 170.0, 205.0, 193.0, 176.0, 208.0, 223.0, 273.0, 277.0, 197.0], "value": 212.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 104.0, 73.0, 158.0, 84.0, 87.0, 76.0, 58.0, 123.0, 56.0], "value": 87.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 118.0, 72.0, 59.0, 65.0, 126.0, 100.0, 63.0, 114.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 90.0, 89.0, 91.0, 88.0, 89.0, 90.0, 91.0, 89.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 96.0, 125.0, 141.0, 151.0, 131.0, 96.0, 96.0, 97.0, 97.0], "value": 113.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 134.0, 91.0, 92.0, 91.0, 89.0, 93.0, 91.0, 94.0, 89.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 46.0, 47.0, 51.0, 51.0, 50.0, 50.0, 50.0, 49.0, 54.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 61.0, 48.0, 55.0, 56.0, 51.0, 64.0, 70.0, 75.0, 78.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 78.0, 79.0, 78.0, 76.0, 78.0, 78.0, 77.0, 97.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 131.0, 129.0, 129.0, 129.0, 137.0, 136.0, 131.0, 136.0, 164.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 173.0, 173.0, 169.0, 175.0, 175.0, 170.0, 176.0, 187.0, 213.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 67.0, 68.0, 89.0, 84.0, 88.0, 75.0, 63.0], "value": 72.6, "unit": "score"}]}]} 16:05:47 INFO - Return code: 0 16:05:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:05:47 INFO - # TBPL SUCCESS # 16:05:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:05:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:05:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:05:48 INFO - 2017-06-13 16:05:48.009 screenresolution[1675:10983] starting screenresolution argv=screenresolution get 16:05:48 INFO - 2017-06-13 16:05:48.011 screenresolution[1675:10983] Display 0: 1600x1200x32@60 16:05:48 INFO - 2017-06-13 16:05:48.017 screenresolution[1676:10986] starting screenresolution argv=screenresolution list 16:05:48 INFO - Available Modes on Display 0 16:05:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:05:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:05:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:05:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:05:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:05:48 INFO - Intel Iris: 16:05:48 INFO - Chipset Model: Intel Iris 16:05:48 INFO - Type: GPU 16:05:48 INFO - Bus: Built-In 16:05:48 INFO - VRAM (Dynamic, Max): 1536 MB 16:05:48 INFO - Vendor: Intel (0x8086) 16:05:48 INFO - Device ID: 0x0a2e 16:05:48 INFO - Revision ID: 0x0009 16:05:48 INFO - Displays: 16:05:48 INFO - Display: 16:05:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:05:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:05:48 INFO - Display Serial Number: 1600x1200 60 16:05:48 INFO - Main Display: Yes 16:05:48 INFO - Mirror: Off 16:05:48 INFO - Online: Yes 16:05:48 INFO - Rotation: Supported 16:05:48 INFO - Return code: 0 16:05:48 INFO - Running post-action listener: _resource_record_post_action 16:05:48 INFO - [mozharness: 2017-06-13 23:05:48.252392Z] Finished run-tests step (success) 16:05:48 INFO - Running post-run listener: _resource_record_post_run 16:05:48 INFO - Total resource usage - Wall time: 1247s; CPU: 53.0%; Read bytes: 41622016; Write bytes: 473378816; Read time: 274; Write time: 1555 16:05:48 INFO - TinderboxPrint: CPU usage
53.1% 16:05:48 INFO - TinderboxPrint: I/O read bytes / time
41,622,016 / 274 16:05:48 INFO - TinderboxPrint: I/O write bytes / time
473,378,816 / 1,555 16:05:48 INFO - TinderboxPrint: CPU idle
2,341.3 (46.9%) 16:05:48 INFO - TinderboxPrint: CPU system
1,010.1 (20.2%) 16:05:48 INFO - TinderboxPrint: CPU user
1,638.2 (32.8%) 16:05:48 INFO - TinderboxPrint: Swap in / out
194,760,704 / 0 16:05:48 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 154591744; Write bytes: 157214720; Read time: 11967; Write time: 355 16:05:48 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:05:48 INFO - run-tests - Wall time: 1232s; CPU: 53.0%; Read bytes: 37349888; Write bytes: 314677248; Read time: 250; Write time: 1194 16:05:48 INFO - Running post-run listener: _upload_blobber_files 16:05:48 INFO - Blob upload gear active. 16:05:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:05:48 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 16:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:05:49 INFO - (blobuploader) - INFO - Open directory for files ... 16:05:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:05:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:05:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:50 INFO - (blobuploader) - INFO - Done attempting. 16:05:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:05:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:05:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:51 INFO - (blobuploader) - INFO - Done attempting. 16:05:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:05:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:05:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:52 INFO - (blobuploader) - INFO - Done attempting. 16:05:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:05:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:05:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:54 INFO - (blobuploader) - INFO - Done attempting. 16:05:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:05:54 INFO - Return code: 0 16:05:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:05:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:05:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca1bcc5c34f89a9389880ade42d67874e81b546c5130372e0e4affab941708385e9cf2bf0fb2ef2fc7f34d3886b782f6184b537a559e824b865073646b2a6ee7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d3c4bad09d2b1c1046a9f855c4f781d1d43a67a70b64fdfb729ac94b5e864e4e423e583ffb2d4266a7ac6e0d2e0842faae28bc553cadb6cb211570d1c5c9295", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1699874fef06a5a3497885cc244fa72168d72b4b6890b838faa1e59f78a99ddc0e3a96d679e5bb35b8d0dd022cab9492d42b1362c16e8ca8bd4e481326c58dc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac1ab37e981eb33b00dd4fd0d72073b897cf94db35bcdf214c9e0d18f156292644f3d428c54ba2fbc53dc64736fd88f0bc629e564045a97ad28b5920a10f2ec"} 16:05:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:05:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:05:54 INFO - Contents: 16:05:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca1bcc5c34f89a9389880ade42d67874e81b546c5130372e0e4affab941708385e9cf2bf0fb2ef2fc7f34d3886b782f6184b537a559e824b865073646b2a6ee7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d3c4bad09d2b1c1046a9f855c4f781d1d43a67a70b64fdfb729ac94b5e864e4e423e583ffb2d4266a7ac6e0d2e0842faae28bc553cadb6cb211570d1c5c9295", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1699874fef06a5a3497885cc244fa72168d72b4b6890b838faa1e59f78a99ddc0e3a96d679e5bb35b8d0dd022cab9492d42b1362c16e8ca8bd4e481326c58dc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac1ab37e981eb33b00dd4fd0d72073b897cf94db35bcdf214c9e0d18f156292644f3d428c54ba2fbc53dc64736fd88f0bc629e564045a97ad28b5920a10f2ec"} 16:05:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:05:54 INFO - Copying logs to upload dir... 16:05:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:05:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1329.890004 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 9 secs) (at 2017-06-13 16:05:54.567871) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 16:05:54.571290) ========= 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.QsUhTX75Xl/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.O1PLTVUN13/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca1bcc5c34f89a9389880ade42d67874e81b546c5130372e0e4affab941708385e9cf2bf0fb2ef2fc7f34d3886b782f6184b537a559e824b865073646b2a6ee7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d3c4bad09d2b1c1046a9f855c4f781d1d43a67a70b64fdfb729ac94b5e864e4e423e583ffb2d4266a7ac6e0d2e0842faae28bc553cadb6cb211570d1c5c9295", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1699874fef06a5a3497885cc244fa72168d72b4b6890b838faa1e59f78a99ddc0e3a96d679e5bb35b8d0dd022cab9492d42b1362c16e8ca8bd4e481326c58dc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac1ab37e981eb33b00dd4fd0d72073b897cf94db35bcdf214c9e0d18f156292644f3d428c54ba2fbc53dc64736fd88f0bc629e564045a97ad28b5920a10f2ec"} build_url:https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/eBg7TPdLQxSKEQ0cweLpmg/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca1bcc5c34f89a9389880ade42d67874e81b546c5130372e0e4affab941708385e9cf2bf0fb2ef2fc7f34d3886b782f6184b537a559e824b865073646b2a6ee7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d3c4bad09d2b1c1046a9f855c4f781d1d43a67a70b64fdfb729ac94b5e864e4e423e583ffb2d4266a7ac6e0d2e0842faae28bc553cadb6cb211570d1c5c9295", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1699874fef06a5a3497885cc244fa72168d72b4b6890b838faa1e59f78a99ddc0e3a96d679e5bb35b8d0dd022cab9492d42b1362c16e8ca8bd4e481326c58dc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac1ab37e981eb33b00dd4fd0d72073b897cf94db35bcdf214c9e0d18f156292644f3d428c54ba2fbc53dc64736fd88f0bc629e564045a97ad28b5920a10f2ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 16:05:54.605624) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 16:05:54.606027) ========= 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.QsUhTX75Xl/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.O1PLTVUN13/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.005070 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 16:05:54.655196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 16:05:54.655502) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 16:05:54.655839) ========= ========= Total master_lag: 0.17 =========