builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0005 starttime: 1497281199.88 results: success (0) revision: 9eb9e6b53887119997909f0b5e773138b76d8c7e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:39.880948) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:39.881387) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:39.881714) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:39.904976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:39.905286) ========= 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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-12 08:26:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-06-12 08:26:40 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132270 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:40.059742) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:40.060063) ========= 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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.022510 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:40.111940) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-12 08:26:40.112857) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9eb9e6b53887119997909f0b5e773138b76d8c7e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 9eb9e6b53887119997909f0b5e773138b76d8c7e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZdfT6v7N0o/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.vgfykMUkEE/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-12 08:26:40,188 truncating revision to first 12 chars 2017-06-12 08:26:40,188 Setting DEBUG logging. 2017-06-12 08:26:40,188 attempt 1/10 2017-06-12 08:26:40,188 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9eb9e6b53887?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 08:26:42,239 unpacking tar archive at: mozilla-central-9eb9e6b53887/testing/mozharness/ program finished with exit code 0 elapsedTime=2.318133 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-12 08:26:42.456558) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:42.457031) ========= script_repo_revision: 9eb9e6b53887119997909f0b5e773138b76d8c7e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:42.457494) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:42.458016) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 08:26:42.478442) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-06-12 08:26:42.479089) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZdfT6v7N0o/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.vgfykMUkEE/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:26:42 INFO - MultiFileLogger online at 20170612 08:26:42 in /builds/slave/test 08:26:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:26:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:26:42 INFO - {'append_to_log': False, 08:26:42 INFO - 'base_work_dir': '/builds/slave/test', 08:26:42 INFO - 'blob_upload_branch': 'Firefox', 08:26:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:26:42 INFO - 'branch': 'Firefox', 08:26:42 INFO - 'buildbot_json_path': 'buildprops.json', 08:26:42 INFO - 'config_files': ('talos/mac_config.py',), 08:26:42 INFO - 'default_actions': ('clobber', 08:26:42 INFO - 'read-buildbot-config', 08:26:42 INFO - 'download-and-extract', 08:26:42 INFO - 'populate-webroot', 08:26:42 INFO - 'create-virtualenv', 08:26:42 INFO - 'install', 08:26:42 INFO - 'setup-mitmproxy', 08:26:42 INFO - 'run-tests'), 08:26:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:26:42 INFO - 'download_minidump_stackwalk': True, 08:26:42 INFO - 'download_symbols': 'ondemand', 08:26:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:26:42 INFO - 'tooltool.py': '/tools/tooltool.py', 08:26:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:26:42 INFO - '/tools/misc-python/virtualenv.py')}, 08:26:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:26:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:26:42 INFO - 'gecko_profile': False, 08:26:42 INFO - 'gecko_profile_interval': 0, 08:26:42 INFO - 'installer_path': 'installer.exe', 08:26:42 INFO - 'log_level': 'info', 08:26:42 INFO - 'log_name': 'talos', 08:26:42 INFO - 'log_to_console': True, 08:26:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:26:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:26:42 INFO - 'opt_config_files': (), 08:26:42 INFO - 'pip_index': False, 08:26:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:26:42 INFO - 'cmd': ('bash', 08:26:42 INFO - '-c', 08:26:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:26:42 INFO - 'enabled': True, 08:26:42 INFO - 'halt_on_failure': False, 08:26:42 INFO - 'name': 'check_screen_resolution'},), 08:26:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:26:42 INFO - 'cmd': ('bash', 08:26:42 INFO - '-c', 08:26:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:26:42 INFO - 'enabled': True, 08:26:42 INFO - 'halt_on_failure': False, 08:26:42 INFO - 'name': 'check_screen_resolution'},), 08:26:42 INFO - 'run_cmd_checks_enabled': True, 08:26:42 INFO - 'suite': 'dromaeojs-e10s', 08:26:42 INFO - 'system_bits': '32', 08:26:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:26:42 INFO - 'title': 't-yosemite-r7-0005', 08:26:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:26:42 INFO - 'use_talos_json': True, 08:26:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:26:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:26:42 INFO - 'work_dir': 'build'} 08:26:42 INFO - [mozharness: 2017-06-12 15:26:42.688999Z] Running clobber step. 08:26:42 INFO - Running pre-action listener: _resource_record_pre_action 08:26:42 INFO - Running main action method: clobber 08:26:42 INFO - rmtree: /builds/slave/test/build 08:26:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:26:44 INFO - Running post-action listener: _resource_record_post_action 08:26:44 INFO - [mozharness: 2017-06-12 15:26:44.903604Z] Finished clobber step (success) 08:26:44 INFO - [mozharness: 2017-06-12 15:26:44.903775Z] Running read-buildbot-config step. 08:26:44 INFO - Running pre-action listener: _resource_record_pre_action 08:26:44 INFO - Running main action method: read_buildbot_config 08:26:44 INFO - Using buildbot properties: 08:26:44 INFO - { 08:26:44 INFO - "project": "", 08:26:44 INFO - "product": "firefox", 08:26:44 INFO - "who": "bhearsum@mozilla.com", 08:26:44 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 08:26:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:26:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:26:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:26:44 INFO - "stage_platform": "macosx64", 08:26:44 INFO - "basedir": "/builds/slave/test", 08:26:44 INFO - "buildnumber": 235, 08:26:44 INFO - "platform": "yosemite_r7", 08:26:44 INFO - "parent_task_id": "GuUu_pQPTUKn3RWXhZe67g", 08:26:44 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:26:44 INFO - "slavebuilddir": "test", 08:26:44 INFO - "taskId": "P6oJRHADScmL0u1_6PgKOg", 08:26:44 INFO - "branch": "mozilla-central", 08:26:44 INFO - "script_repo_revision": "production", 08:26:44 INFO - "revision": "9eb9e6b53887119997909f0b5e773138b76d8c7e", 08:26:44 INFO - "slavename": "t-yosemite-r7-0005", 08:26:44 INFO - "repo_path": "mozilla-central" 08:26:44 INFO - } 08:26:44 INFO - Finding installer, test and symbols from parent task. 08:26:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P6oJRHADScmL0u1_6PgKOg'}, attempt #1 08:26:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P6oJRHADScmL0u1_6PgKOg'}, attempt #1 08:26:46 INFO - Task dependencies: CxSp7ovGTAOxE0sgpejb7g GuUu_pQPTUKn3RWXhZe67g 08:26:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g'}, attempt #1 08:26:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 08:26:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/test_packages.json 08:26:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/target.crashreporter-symbols.zip 08:26:46 INFO - Running post-action listener: _resource_record_post_action 08:26:46 INFO - [mozharness: 2017-06-12 15:26:46.616296Z] Finished read-buildbot-config step (success) 08:26:46 INFO - [mozharness: 2017-06-12 15:26:46.616442Z] Running download-and-extract step. 08:26:46 INFO - Running pre-action listener: _resource_record_pre_action 08:26:46 INFO - Running main action method: download_and_extract 08:26:46 INFO - mkdir: /builds/slave/test/build/tests 08:26:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:46 INFO - trying https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 08:26:46 INFO - Downloading https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 08:26:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 08:26:47 INFO - Downloaded 1328 bytes. 08:26:47 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 08:26:47 INFO - Using the following test package requirements: 08:26:47 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 08:26:47 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 08:26:47 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 08:26:47 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 08:26:47 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'jsshell-mac.zip'], 08:26:47 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 08:26:47 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 08:26:47 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 08:26:47 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 08:26:47 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 08:26:47 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 08:26:47 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 08:26:47 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:26:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 08:26:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:26:47 INFO - Fetch https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 08:26:49 INFO - Content-Length response header: 59541228 08:26:49 INFO - Bytes received: 59541228 08:27:07 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:27:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 08:27:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:27:07 INFO - Fetch https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 08:27:10 INFO - Content-Length response header: 59541228 08:27:10 INFO - Bytes received: 59541228 08:27:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 08:27:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:27:27 INFO - Fetch https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 08:27:28 INFO - Content-Length response header: 14450010 08:27:28 INFO - Bytes received: 14450010 08:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:29 INFO - trying https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 08:27:29 INFO - Downloading https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 08:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 08:27:31 INFO - Downloaded 69312219 bytes. 08:27:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 08:27:31 INFO - mkdir: /builds/slave/test/properties 08:27:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:27:31 INFO - Writing to file /builds/slave/test/properties/build_url 08:27:31 INFO - Contents: 08:27:31 INFO - build_url:https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 08:27:31 INFO - Running post-action listener: _resource_record_post_action 08:27:31 INFO - Running post-action listener: set_extra_try_arguments 08:27:31 INFO - [mozharness: 2017-06-12 15:27:31.605620Z] Finished download-and-extract step (success) 08:27:31 INFO - [mozharness: 2017-06-12 15:27:31.605755Z] Running populate-webroot step. 08:27:31 INFO - Running pre-action listener: _resource_record_pre_action 08:27:31 INFO - Running main action method: populate_webroot 08:27:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:27:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:27:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:27:31 INFO - '--tppagecycles', 08:27:31 INFO - '7'], 08:27:31 INFO - 'tests': ['tsvgm']}}, 08:27:31 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:27:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:27:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:27:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:27:31 INFO - 'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:27:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:27:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:27:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:27:31 INFO - 'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['damp', 'tps']}, 08:27:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:27:31 INFO - 'tests': ['damp', 'tps']}, 08:27:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['dromaeo_dom']}, 08:27:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:27:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:27:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:27:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:27:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:27:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['a11yr', 08:27:31 INFO - 'ts_paint', 08:27:31 INFO - 'tpaint', 08:27:31 INFO - 'sessionrestore', 08:27:31 INFO - 'sessionrestore_no_auto_restore', 08:27:31 INFO - 'tabpaint']}, 08:27:31 INFO - 'other-e10s': {'tests': ['a11yr', 08:27:31 INFO - 'ts_paint', 08:27:31 INFO - 'tpaint', 08:27:31 INFO - 'sessionrestore', 08:27:31 INFO - 'sessionrestore_no_auto_restore', 08:27:31 INFO - 'tabpaint']}, 08:27:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:27:31 INFO - 'ts_paint', 08:27:31 INFO - 'tpaint', 08:27:31 INFO - 'sessionrestore', 08:27:31 INFO - 'sessionrestore_no_auto_restore', 08:27:31 INFO - 'tabpaint']}, 08:27:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:27:31 INFO - 'ts_paint', 08:27:31 INFO - 'tpaint', 08:27:31 INFO - 'sessionrestore', 08:27:31 INFO - 'sessionrestore_no_auto_restore', 08:27:31 INFO - 'tabpaint']}, 08:27:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['a11yr', 08:27:31 INFO - 'ts_paint', 08:27:31 INFO - 'tpaint', 08:27:31 INFO - 'sessionrestore', 08:27:31 INFO - 'sessionrestore_no_auto_restore', 08:27:31 INFO - 'tabpaint']}, 08:27:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['a11yr', 08:27:31 INFO - 'ts_paint', 08:27:31 INFO - 'tpaint', 08:27:31 INFO - 'sessionrestore', 08:27:31 INFO - 'sessionrestore_no_auto_restore', 08:27:31 INFO - 'tabpaint']}, 08:27:31 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:27:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:27:31 INFO - 'bloom_basic_ref']}, 08:27:31 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:27:31 INFO - 'talos_options': ['--mitmproxy', 08:27:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:27:31 INFO - '--firstNonBlankPaint'], 08:27:31 INFO - 'tests': ['quantum_pageload_google', 08:27:31 INFO - 'quantum_pageload_youtube', 08:27:31 INFO - 'quantum_pageload_amazon', 08:27:31 INFO - 'quantum_pageload_facebook']}, 08:27:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['tsvgx', 08:27:31 INFO - 'tsvgr_opacity', 08:27:31 INFO - 'tart', 08:27:31 INFO - 'tscrollx', 08:27:31 INFO - 'cart', 08:27:31 INFO - 'tsvg_static']}, 08:27:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:27:31 INFO - 'tsvgr_opacity', 08:27:31 INFO - 'tart', 08:27:31 INFO - 'tscrollx', 08:27:31 INFO - 'cart', 08:27:31 INFO - 'tsvg_static']}, 08:27:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:27:31 INFO - 'talos_options': ['--disable-e10s'], 08:27:31 INFO - 'tests': ['tp5o']}, 08:27:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:27:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:27:31 INFO - 'talos_options': ['--disable-e10s', 08:27:31 INFO - '--xperf_path', 08:27:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:27:31 INFO - 'tests': ['tp5n']}, 08:27:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:27:31 INFO - 'talos_options': ['--xperf_path', 08:27:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:27:31 INFO - 'tests': ['tp5n']}}, 08:27:31 INFO - 'talos.zip': {'path': '', 08:27:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:27:31 INFO - Running post-action listener: _resource_record_post_action 08:27:31 INFO - [mozharness: 2017-06-12 15:27:31.628536Z] Finished populate-webroot step (success) 08:27:31 INFO - [mozharness: 2017-06-12 15:27:31.628675Z] Running create-virtualenv step. 08:27:31 INFO - Running pre-action listener: _resource_record_pre_action 08:27:31 INFO - Running main action method: create_virtualenv 08:27:31 INFO - Creating virtualenv /builds/slave/test/build/venv 08:27:31 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 08:27:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:27:31 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:27:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:27:31 INFO - Using real prefix '/tools/python27' 08:27:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:27:32 INFO - Installing distribute.............................................................................................................................................................................................done. 08:27:35 INFO - Installing pip.................done. 08:27:35 INFO - Return code: 0 08:27:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:27:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:27:35 INFO - Reading from file tmpfile_stdout 08:27:35 INFO - Output received: 08:27:35 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) 08:27:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:27:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27: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')]} 08:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27: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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27: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 08:27: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 08:27:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:36 INFO - 'HOME': '/Users/cltbld', 08:27:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:36 INFO - 'LOGNAME': 'cltbld', 08:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:36 INFO - 'MOZ_NO_REMOTE': '1', 08:27:36 INFO - 'NO_EM_RESTART': '1', 08:27:36 INFO - 'PAGER': '/bin/cat', 08:27:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:36 INFO - 'PWD': '/builds/slave/test', 08:27:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:36 INFO - 'SHELL': '/bin/bash', 08:27:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:36 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:36 INFO - 'USER': 'cltbld', 08:27:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:36 INFO - 'XPC_FLAGS': '0x0', 08:27:36 INFO - 'XPC_SERVICE_NAME': '0', 08:27:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:37 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 08:27:37 INFO - Cleaning up... 08:27:37 INFO - Return code: 0 08:27:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:27:37 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')]} 08:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:37 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')]} 08:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:37 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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27:37 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 08:27:37 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 08:27:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:37 INFO - 'HOME': '/Users/cltbld', 08:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:37 INFO - 'LOGNAME': 'cltbld', 08:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:37 INFO - 'MOZ_NO_REMOTE': '1', 08:27:37 INFO - 'NO_EM_RESTART': '1', 08:27:37 INFO - 'PAGER': '/bin/cat', 08:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:37 INFO - 'PWD': '/builds/slave/test', 08:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:37 INFO - 'SHELL': '/bin/bash', 08:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:37 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:37 INFO - 'USER': 'cltbld', 08:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:37 INFO - 'XPC_FLAGS': '0x0', 08:27:37 INFO - 'XPC_SERVICE_NAME': '0', 08:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:37 INFO - Downloading/unpacking psutil>=3.1.1 08:27:37 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 08:27:37 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 08:27:37 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 08:27:37 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 08:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:27:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:27:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:27:40 INFO - Installing collected packages: psutil 08:27:40 INFO - Running setup.py install for psutil 08:27:40 INFO - building 'psutil._psutil_osx' extension 08:27:40 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 08:27:40 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 08:27:40 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 08:27:40 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 08:27:40 INFO - building 'psutil._psutil_posix' extension 08:27:40 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 08:27:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:27:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:27:40 INFO - ^ 08:27:40 INFO - 1 warning generated. 08:27:40 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 08:27:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:27:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:27:40 INFO - Successfully installed psutil 08:27:40 INFO - Cleaning up... 08:27:40 INFO - Return code: 0 08:27:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27: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')]} 08:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27: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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27: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 08:27: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 08:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:40 INFO - 'HOME': '/Users/cltbld', 08:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:40 INFO - 'LOGNAME': 'cltbld', 08:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:40 INFO - 'MOZ_NO_REMOTE': '1', 08:27:40 INFO - 'NO_EM_RESTART': '1', 08:27:40 INFO - 'PAGER': '/bin/cat', 08:27:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:40 INFO - 'PWD': '/builds/slave/test', 08:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:40 INFO - 'SHELL': '/bin/bash', 08:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:40 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:40 INFO - 'USER': 'cltbld', 08:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:40 INFO - 'XPC_FLAGS': '0x0', 08:27:40 INFO - 'XPC_SERVICE_NAME': '0', 08:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:27: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 08:27: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 08:27: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 08:27: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 08:27:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:27:42 INFO - Installing collected packages: mozsystemmonitor 08:27:42 INFO - Running setup.py install for mozsystemmonitor 08:27:42 INFO - Successfully installed mozsystemmonitor 08:27:42 INFO - Cleaning up... 08:27:42 INFO - Return code: 0 08:27:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:27:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:27:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:42 INFO - 'HOME': '/Users/cltbld', 08:27:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:42 INFO - 'LOGNAME': 'cltbld', 08:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:42 INFO - 'MOZ_NO_REMOTE': '1', 08:27:42 INFO - 'NO_EM_RESTART': '1', 08:27:42 INFO - 'PAGER': '/bin/cat', 08:27:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:42 INFO - 'PWD': '/builds/slave/test', 08:27:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:42 INFO - 'SHELL': '/bin/bash', 08:27:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:42 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:42 INFO - 'USER': 'cltbld', 08:27:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:42 INFO - 'XPC_FLAGS': '0x0', 08:27:42 INFO - 'XPC_SERVICE_NAME': '0', 08:27:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:43 INFO - Downloading/unpacking jsonschema==2.5.1 08:27: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 08:27: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 08:27: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 08:27: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 08:27:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:27:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:27: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 08:27: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 08:27: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 08:27: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 08:27:44 INFO - Downloading functools32-3.2.3-2.tar.gz 08:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:27:44 INFO - warning: no files found matching '*.txt' 08:27:44 INFO - no previously-included directories found matching 'build' 08:27:44 INFO - no previously-included directories found matching 'dist' 08:27:44 INFO - no previously-included directories found matching '.git*' 08:27:44 INFO - Installing collected packages: jsonschema, functools32 08:27:44 INFO - Running setup.py install for functools32 08:27:44 INFO - warning: no files found matching '*.txt' 08:27:44 INFO - no previously-included directories found matching 'build' 08:27:44 INFO - no previously-included directories found matching 'dist' 08:27:44 INFO - no previously-included directories found matching '.git*' 08:27:45 INFO - Successfully installed jsonschema functools32 08:27:45 INFO - Cleaning up... 08:27:45 INFO - Return code: 0 08:27:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27: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')]} 08:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27: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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27: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 08:27: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 08:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:45 INFO - 'HOME': '/Users/cltbld', 08:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:45 INFO - 'LOGNAME': 'cltbld', 08:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:45 INFO - 'MOZ_NO_REMOTE': '1', 08:27:45 INFO - 'NO_EM_RESTART': '1', 08:27:45 INFO - 'PAGER': '/bin/cat', 08:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:45 INFO - 'PWD': '/builds/slave/test', 08:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:45 INFO - 'SHELL': '/bin/bash', 08:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:45 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:45 INFO - 'USER': 'cltbld', 08:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:45 INFO - 'XPC_FLAGS': '0x0', 08:27:45 INFO - 'XPC_SERVICE_NAME': '0', 08:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:27:45 INFO - Cleaning up... 08:27:45 INFO - Return code: 0 08:27:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27: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')]} 08:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27: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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27: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 08:27: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 08:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:45 INFO - 'HOME': '/Users/cltbld', 08:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:45 INFO - 'LOGNAME': 'cltbld', 08:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:45 INFO - 'MOZ_NO_REMOTE': '1', 08:27:45 INFO - 'NO_EM_RESTART': '1', 08:27:45 INFO - 'PAGER': '/bin/cat', 08:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:45 INFO - 'PWD': '/builds/slave/test', 08:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:45 INFO - 'SHELL': '/bin/bash', 08:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:45 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:45 INFO - 'USER': 'cltbld', 08:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:45 INFO - 'XPC_FLAGS': '0x0', 08:27:45 INFO - 'XPC_SERVICE_NAME': '0', 08:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:45 INFO - Downloading/unpacking blobuploader==1.2.4 08:27: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 08:27: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 08:27: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 08:27: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 08:27:46 INFO - Downloading blobuploader-1.2.4.tar.gz 08:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:27:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:27: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 08:27: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 08:27: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 08:27: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 08:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:27:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:27: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 08:27: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 08:27: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 08:27: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 08:27:47 INFO - Downloading docopt-0.6.1.tar.gz 08:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:27:47 INFO - Installing collected packages: blobuploader, requests, docopt 08:27:47 INFO - Running setup.py install for blobuploader 08:27:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:27:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:27:48 INFO - Running setup.py install for requests 08:27:48 INFO - Running setup.py install for docopt 08:27:48 INFO - Successfully installed blobuploader requests docopt 08:27:48 INFO - Cleaning up... 08:27:48 INFO - Return code: 0 08:27:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27: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')]} 08:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27: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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27: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 08:27: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 08:27:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:48 INFO - 'HOME': '/Users/cltbld', 08:27:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:48 INFO - 'LOGNAME': 'cltbld', 08:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:48 INFO - 'MOZ_NO_REMOTE': '1', 08:27:48 INFO - 'NO_EM_RESTART': '1', 08:27:48 INFO - 'PAGER': '/bin/cat', 08:27:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:48 INFO - 'PWD': '/builds/slave/test', 08:27:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:48 INFO - 'SHELL': '/bin/bash', 08:27:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:48 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:48 INFO - 'USER': 'cltbld', 08:27:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:48 INFO - 'XPC_FLAGS': '0x0', 08:27:48 INFO - 'XPC_SERVICE_NAME': '0', 08:27:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-wfwyha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-eIMpzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yUlQOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-8KYoo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-60YOuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-nPkXkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-6W8_Kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Ovp6qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:27:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-EFIfI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:27:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-3KM9ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:27:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-52U8WD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:27:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-PzWPRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:27:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-DLYqtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:27:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-FwM3eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UEvIkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:27:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-hlV_aN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:27:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Kbpob4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:27:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:27:50 INFO - Running setup.py install for manifestparser 08:27:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:27:51 INFO - Running setup.py install for mozcrash 08:27:51 INFO - Running setup.py install for mozdebug 08:27:51 INFO - Running setup.py install for mozdevice 08:27:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:27:51 INFO - Running setup.py install for mozfile 08:27:51 INFO - Running setup.py install for mozhttpd 08:27:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:27:51 INFO - Running setup.py install for mozinfo 08:27:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:27:51 INFO - Running setup.py install for mozInstall 08:27:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Running setup.py install for mozleak 08:27:52 INFO - Running setup.py install for mozlog 08:27:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Running setup.py install for moznetwork 08:27:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Running setup.py install for mozprocess 08:27:52 INFO - Running setup.py install for mozprofile 08:27:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:27:52 INFO - Running setup.py install for mozrunner 08:27:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:27:53 INFO - Running setup.py install for mozscreenshot 08:27:53 INFO - Running setup.py install for moztest 08:27:53 INFO - Running setup.py install for mozversion 08:27:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:27:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:27:53 INFO - Cleaning up... 08:27:53 INFO - Return code: 0 08:27:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27: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')]} 08:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27: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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27: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 08:27: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 08:27:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:53 INFO - 'HOME': '/Users/cltbld', 08:27:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:53 INFO - 'LOGNAME': 'cltbld', 08:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:53 INFO - 'MOZ_NO_REMOTE': '1', 08:27:53 INFO - 'NO_EM_RESTART': '1', 08:27:53 INFO - 'PAGER': '/bin/cat', 08:27:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:53 INFO - 'PWD': '/builds/slave/test', 08:27:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:53 INFO - 'SHELL': '/bin/bash', 08:27:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:53 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:53 INFO - 'USER': 'cltbld', 08:27:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:53 INFO - 'XPC_FLAGS': '0x0', 08:27:53 INFO - 'XPC_SERVICE_NAME': '0', 08:27:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:27:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-wwHzWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:27: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)) 08:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:27:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-lsgXUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:27: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)) 08:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:27:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-jPtnNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Fkc_Ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-WO4puT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-I0A9l8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Z4nMUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-HVAHRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Cet0OQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-LkzQ7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Spille-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:27: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)) 08:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:27:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-uqSQbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:27: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)) 08:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:27:55 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-_C5A1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:27: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)) 08:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:27:55 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-YhV8WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:27: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)) 08:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:55 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UKmy6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:27: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)) 08:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:27:55 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-AZEhFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:27: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)) 08:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:27:55 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-cOqW_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:27: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 08:27: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 08:27: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 08:27: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 08:27:57 INFO - Downloading blessings-1.6.tar.gz 08:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:27:57 INFO - Installing collected packages: blessings 08:27:57 INFO - Running setup.py install for blessings 08:27:57 INFO - Successfully installed blessings 08:27:57 INFO - Cleaning up... 08:27:57 INFO - Return code: 0 08:27:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:27:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:27:57 INFO - Reading from file tmpfile_stdout 08:27:57 INFO - Current package versions: 08:27:57 INFO - blessings == 1.6 08:27:57 INFO - blobuploader == 1.2.4 08:27:57 INFO - docopt == 0.6.1 08:27:57 INFO - functools32 == 3.2.3-2 08:27:57 INFO - jsonschema == 2.5.1 08:27:57 INFO - manifestparser == 1.1 08:27:57 INFO - mozInstall == 1.13 08:27:57 INFO - mozcrash == 1.0 08:27:57 INFO - mozdebug == 0.1 08:27:57 INFO - mozdevice == 0.51 08:27:57 INFO - mozfile == 1.2 08:27:57 INFO - mozhttpd == 0.7 08:27:57 INFO - mozinfo == 0.10 08:27:57 INFO - mozleak == 0.1 08:27:57 INFO - mozlog == 3.4 08:27:57 INFO - moznetwork == 0.27 08:27:57 INFO - mozprocess == 0.25 08:27:57 INFO - mozprofile == 0.28 08:27:57 INFO - mozrunner == 6.13 08:27:57 INFO - mozscreenshot == 0.1 08:27:57 INFO - mozsystemmonitor == 0.3 08:27:57 INFO - moztest == 0.8 08:27:57 INFO - mozversion == 1.4 08:27:57 INFO - psutil == 3.1.1 08:27:57 INFO - requests == 1.2.3 08:27:57 INFO - wsgiref == 0.1.2 08:27:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27: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')]} 08:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27: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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27: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 08:27: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 08:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:57 INFO - 'HOME': '/Users/cltbld', 08:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:57 INFO - 'LOGNAME': 'cltbld', 08:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:57 INFO - 'MOZ_NO_REMOTE': '1', 08:27:57 INFO - 'NO_EM_RESTART': '1', 08:27:57 INFO - 'PAGER': '/bin/cat', 08:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:57 INFO - 'PWD': '/builds/slave/test', 08:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:57 INFO - 'SHELL': '/bin/bash', 08:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:57 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:57 INFO - 'USER': 'cltbld', 08:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:57 INFO - 'XPC_FLAGS': '0x0', 08:27:57 INFO - 'XPC_SERVICE_NAME': '0', 08:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27:57 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:27: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 08:27: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 08:27: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 08:27: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 08:27:59 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:27: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)) 08:27: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)) 08:27: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)) 08:27: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)) 08:27:59 INFO - Installing collected packages: marionette-driver 08:27:59 INFO - Running setup.py install for marionette-driver 08:27:59 INFO - Successfully installed marionette-driver 08:27:59 INFO - Cleaning up... 08:27:59 INFO - Return code: 0 08:27:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27: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')]} 08:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27: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 0x101b6ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda7acb2dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, '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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 08:27: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 08:27: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 08:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:59 INFO - 'HOME': '/Users/cltbld', 08:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:59 INFO - 'LOGNAME': 'cltbld', 08:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:59 INFO - 'MOZ_NO_REMOTE': '1', 08:27:59 INFO - 'NO_EM_RESTART': '1', 08:27:59 INFO - 'PAGER': '/bin/cat', 08:27:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:59 INFO - 'PWD': '/builds/slave/test', 08:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:59 INFO - 'SHELL': '/bin/bash', 08:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:27:59 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:59 INFO - 'USER': 'cltbld', 08:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:59 INFO - 'XPC_FLAGS': '0x0', 08:27:59 INFO - 'XPC_SERVICE_NAME': '0', 08:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:27:59 INFO - Cleaning up... 08:28:00 INFO - Return code: 0 08:28:00 INFO - Running post-action listener: _resource_record_post_action 08:28:00 INFO - Running post-action listener: _start_resource_monitoring 08:28:00 INFO - Starting resource monitoring. 08:28:00 INFO - [mozharness: 2017-06-12 15:28:00.029274Z] Finished create-virtualenv step (success) 08:28:00 INFO - [mozharness: 2017-06-12 15:28:00.030374Z] Running install step. 08:28:00 INFO - Running pre-action listener: _resource_record_pre_action 08:28:00 INFO - Running main action method: install 08:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:28:00 INFO - Reading from file tmpfile_stdout 08:28:00 INFO - Detecting whether we're running mozinstall >=1.0... 08:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:28:00 INFO - Reading from file tmpfile_stdout 08:28:00 INFO - Output received: 08:28:00 INFO - Usage: mozinstall [options] installer 08:28:00 INFO - Options: 08:28:00 INFO - -h, --help show this help message and exit 08:28:00 INFO - -d DEST, --destination=DEST 08:28:00 INFO - Directory to install application into. [default: 08:28:00 INFO - "/builds/slave/test"] 08:28:00 INFO - --app=APP Application being installed. [default: firefox] 08:28:00 INFO - mkdir: /builds/slave/test/build/application 08:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:28:22 INFO - Reading from file tmpfile_stdout 08:28:22 INFO - Output received: 08:28:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:28:22 INFO - Running post-action listener: _resource_record_post_action 08:28:22 INFO - [mozharness: 2017-06-12 15:28:22.221040Z] Finished install step (success) 08:28:22 INFO - [mozharness: 2017-06-12 15:28:22.221167Z] Running setup-mitmproxy step. 08:28:22 INFO - Running pre-action listener: _resource_record_pre_action 08:28:22 INFO - Running main action method: setup_mitmproxy 08:28:22 INFO - Skipping: mitmproxy is not required 08:28:22 INFO - Running post-action listener: _resource_record_post_action 08:28:22 INFO - [mozharness: 2017-06-12 15:28:22.221852Z] Finished setup-mitmproxy step (success) 08:28:22 INFO - [mozharness: 2017-06-12 15:28:22.221992Z] Running run-tests step. 08:28:22 INFO - Running pre-action listener: _resource_record_pre_action 08:28:22 INFO - Running main action method: run_tests 08:28:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:28:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:28:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:28:22 INFO - 2017-06-12 08:28:22.236 screenresolution[2601:22818] starting screenresolution argv=screenresolution get 08:28:22 INFO - 2017-06-12 08:28:22.239 screenresolution[2601:22818] Display 0: 1600x1200x32@60 08:28:22 INFO - 2017-06-12 08:28:22.246 screenresolution[2602:22821] starting screenresolution argv=screenresolution list 08:28:22 INFO - Available Modes on Display 0 08:28:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:28:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:28:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:28:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:28:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:28:22 INFO - Intel Iris: 08:28:22 INFO - Chipset Model: Intel Iris 08:28:22 INFO - Type: GPU 08:28:22 INFO - Bus: Built-In 08:28:22 INFO - VRAM (Dynamic, Max): 1536 MB 08:28:22 INFO - Vendor: Intel (0x8086) 08:28:22 INFO - Device ID: 0x0a2e 08:28:22 INFO - Revision ID: 0x0009 08:28:22 INFO - Displays: 08:28:22 INFO - Display: 08:28:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:28:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:28:22 INFO - Display Serial Number: 1600x1200 60 08:28:22 INFO - Main Display: Yes 08:28:22 INFO - Mirror: Off 08:28:22 INFO - Online: Yes 08:28:22 INFO - Rotation: Supported 08:28:22 INFO - Return code: 0 08:28:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:28:22 INFO - Python 2.7.3 08:28:22 INFO - Return code: 0 08:28:22 INFO - grabbing minidump binary from tooltool 08:28:22 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')]} 08:28:22 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 0x1018c4d30>, '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 0x10188ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:28:22 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 08:28:22 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 08:28:22 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 08:28:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:28:22 INFO - Return code: 0 08:28:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:28:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:28:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:28:22 INFO - ENV: RUST_BACKTRACE is now 1 08:28:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:28:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:28:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 08:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 08:28:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZdfT6v7N0o/Render', 08:28:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:22 INFO - 'HOME': '/Users/cltbld', 08:28:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:22 INFO - 'LOGNAME': 'cltbld', 08:28:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:28:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:22 INFO - 'MOZ_NO_REMOTE': '1', 08:28:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:28:22 INFO - 'NO_EM_RESTART': '1', 08:28:22 INFO - 'PAGER': '/bin/cat', 08:28:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:22 INFO - 'PWD': '/builds/slave/test', 08:28:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:28:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:22 INFO - 'RUST_BACKTRACE': '1', 08:28:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:28:22 INFO - 'SHELL': '/bin/bash', 08:28:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vgfykMUkEE/Listeners', 08:28:22 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 08:28:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:22 INFO - 'USER': 'cltbld', 08:28:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:22 INFO - 'XPC_FLAGS': '0x0', 08:28:22 INFO - 'XPC_SERVICE_NAME': '0', 08:28:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 08:28:22 INFO - mozversion application_buildid: 20170612065102 08:28:22 INFO - mozversion application_changeset: 9eb9e6b53887119997909f0b5e773138b76d8c7e 08:28:22 INFO - mozversion application_display_name: Nightly 08:28:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:28:22 INFO - mozversion application_name: Firefox 08:28:22 INFO - mozversion application_remotingname: firefox 08:28:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:28:22 INFO - mozversion application_vendor: Mozilla 08:28:22 INFO - mozversion application_version: 56.0a1 08:28:22 INFO - mozversion platform_buildid: 20170612065102 08:28:22 INFO - mozversion platform_changeset: 9eb9e6b53887119997909f0b5e773138b76d8c7e 08:28:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:28:22 INFO - mozversion platform_version: 56.0a1 08:28:22 INFO - using testdate: 1497281302 08:28:22 INFO - actual date: 1497281302 08:28:22 INFO - starting webserver on 'localhost:50262' 08:28:22 INFO - SUITE-START | Running 2 tests 08:28:22 INFO - TEST-START | dromaeo_css 08:28:22 INFO - operating with platform_type : mac_ 08:28:22 INFO - Initialising browser for dromaeo_css test... 08:28:22 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:50262/getInfo.html -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp28WnJX/profile 08:28:22 INFO - TEST-INFO | started process 2612 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:50262/getInfo.html -foreground) 08:28:56 INFO - TEST-INFO | 2612: killed by SIGTERM 08:28:56 INFO - Browser initialized. 08:28:56 INFO - Running cycle 1/1 for dromaeo_css test... 08:28:56 INFO - TEST-INFO | started process 2618 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp28WnJX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:58 INFO - PID 2618 | 2017-06-12 08:28:58.668 plugin-container[2619:23301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 08:28:58 INFO - PID 2618 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:58 INFO - PID 2618 | 2017-06-12 08:28:58.669 plugin-container[2619:23301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f17, name = 'com.apple.CFPasteboardClient' 08:28:58 INFO - PID 2618 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:58 INFO - PID 2618 | 2017-06-12 08:28:58.669 plugin-container[2619:23301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:28:59 INFO - PID 2618 | 2017-06-12 08:28:59.457 plugin-container[2620:23349] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 08:28:59 INFO - PID 2618 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:59 INFO - PID 2618 | 2017-06-12 08:28:59.457 plugin-container[2620:23349] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9917, name = 'com.apple.CFPasteboardClient' 08:28:59 INFO - PID 2618 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:59 INFO - PID 2618 | 2017-06-12 08:28:59.457 plugin-container[2620:23349] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:29:08 INFO - PID 2618 | RSS: Main: 252760064 08:29:08 INFO - PID 2618 | 08:32:11 INFO - PID 2618 | Cycle 1(1): loaded http://localhost:50262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50262/tests/dromaeo/cssquery-ext.html) 08:32:13 INFO - PID 2618 | RSS: Main: 266620928 08:32:13 INFO - PID 2618 | 08:35:15 INFO - PID 2618 | Cycle 1(1): loaded http://localhost:50262/tests/dromaeo/cssquery-ext.html (next: http://localhost:50262/tests/dromaeo/cssquery-jquery.html) 08:35:15 INFO - PID 2618 | RSS: Main: 275300352 08:35:15 INFO - PID 2618 | 08:38:17 INFO - PID 2618 | Cycle 1(1): loaded http://localhost:50262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50262/tests/dromaeo/cssquery-mootools.html) 08:38:18 INFO - PID 2618 | RSS: Main: 277004288 08:38:18 INFO - PID 2618 | 08:41:19 INFO - PID 2618 | Cycle 1(1): loaded http://localhost:50262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50262/tests/dromaeo/cssquery-prototype.html) 08:41:20 INFO - PID 2618 | RSS: Main: 277356544 08:41:20 INFO - PID 2618 | 08:44:23 INFO - PID 2618 | Cycle 1(1): loaded http://localhost:50262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50262/tests/dromaeo/cssquery-yui.html) 08:44:24 INFO - PID 2618 | RSS: Main: 279945216 08:44:24 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | Cycle 1(1): loaded http://localhost:50262/tests/dromaeo/cssquery-yui.html (next: http://localhost:50262/tests/dromaeo/cssquery-dojo.html) 08:47:25 INFO - PID 2618 | RSS: Main: 280215552 08:47:25 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | __start_tp_report 08:47:25 INFO - PID 2618 | _x_x_mozilla_page_load 08:47:25 INFO - PID 2618 | _x_x_mozilla_page_load_details 08:47:25 INFO - PID 2618 | |i|pagename|runs| 08:47:25 INFO - PID 2618 | |0;dojo.html;3441.0365097772633;3490.319387719395;3530.858765649374;3558.4128618777904;3577.3381924344017;4555.74943378114;6017.4283443072945;6049.818505444839;6116.366205733227;6141.915626108638;6062.757489700418;6127.830981140916;6129.969350153254;6135.815925522242;6142.81571552853;5311.62818602698;5322.76047577858;5342.385625653042;5350.224217488471;5356.785728570872;5391.353037635469;5400.162974738922;5433.293671822677;5438.048341540215;5453.127499600065;5362.329708786402;5398.865028374303;5435.83692489226;5440.047991601474;5447.564194864422;5362;5381.165919282498;5409.837704868841;5429.484199001128;5430.70131142787;5321.840344789663;5367;5379.67721936685;5415.729213539307;5420.810271640474;6236.750529978841;6269.811905642838;6277.686115694197;6331.588446750947;6341.207349081365;6372.649504277263;6447.613143211375;6584.604923704593;6591.209054913441;6593.3736295051895;6538.771143009972;6539.574927629689;6616.338366163345;6679.031540426611;6702.430293425016;6341.207349081365;6361.61830290184;6373.649449280288;6374.012028135693;6381.968090159519;6827;6866.725330986805;6877.277885822016;6887.276835932255;6901.482388820858;6482.123526506679;6502.804915852532;6505.21937367519;6513.804585862432;6530.575512591667;5806.564507661942;6420.550561460746;6497.18785151856;6512.023196520559;6519.967400163064;5915.032518357907;6528;6557.049227862029;6564.901526477107;6607.900881486781;6219.222597175353;6321.367863213642;6563;6581.572197807223;6607.834804129935;5192.870178245498;5194;5224.791008359623;5231.424543300234;5283.867903302448;4684;4710.246360582359;4722.858314250578;4729.196036673774;4731.929021064687;6184.628922264678;6268.091126786681;6329.968350158219;6335.3981371769605;6351.951063489459;6641.136652235178;6702.631355275506;6728.226253980757;6728.629925354153;6735.057092007123;5316.468353164652;5327.121025030827;5357.919631205535;6278.058291256348;6320.936790632035;5646.717664116778;5693.971530142323;5809.099589563624;5842.824715258549;5858.912116318259;6659.567128136656;6688.7324507019175;6689.26418094019;6706.59760414367;6727.764528241488;5733.283339582552;6483.059956306215;6657.500687448364;6661.067450557023;6694.397504224448;6786.830329241809;6788.694508747019;6800.387965082969;6807.251202367636;6808.080909077211;6543.051257567721;6555.80332590023;6566.409023187936;6584.275729669828;6599.109120268894;6451.3226111257645;6549.803505894831;6652.368025037614;6666.466682665472;6669.2997235289595;6348.238211414659;6353.174087368612;6454.354564543508;6464.385883341075;6471.25580558223;6272.4982001440685;6345.7144428499905;6372.012338087604;6376.52176086786;6385.616862988236;5509.732761464806;5642.858928526655;6301.6534090625455;6313.2424109107205;6348.111264423077;6345.4923606112325;6370.6814659267775;6371.585846919936;6374.490040796818;6379.362063793584;6241.594296370721;6269.059641053878;6283.937160628518;6312.368763123651;6321.905171422341 08:47:25 INFO - PID 2618 | |1;ext.html;721.4120491799184;727.8617062758076;809.6032943857509;831.2186544648031;1045.634028090169;34430.48354274685;38261.04347391317;38320.80839595802;38377;38420;38334.8083259584;38526.61473385265;38572.80713596428;38615.22769544614;38730.03174920622;7691.461597688178;7833.882491762628;7856.567888766115;7856.7642970710685;7865.292123708865;7700.229977002311;7770.33952114071;7790.026246719161;7812.296893279604;7980.600969951508;6262.342454042306;7762.805929851771;8228.547429891383;8271.131531189229;8283.668653253862;7614.238576142397;7758.418118641096;8108.067572229181;8227.835443291131;8237.546934918577;7989.400794940345;7999.680012799481;8041.19588041197;8043.718469853586;8062.677492900277;35768.10579735501;35861.282774344625;35898;35926;35970.8201458991;3357;3366.5455163553097;3396.728261739055;3398.7111095556907;3446.0867870014467;28747.425051498853;28875;29002;29003.709962900313;29452.70547294521;2953.143588359373;2993.9700602994126;3008.4885569453027;3024.123004328742;3070.9232269193226;243515;248098;248784;281437.5928120367;283545;35989;38072.477100915654;46088;46272;46537.83655408642;49174;49444.505554944706;49692.0061598766;49800.50199497959;49819.25271120936;14047.508337208148;14073.2963351832;14095.71808563823;14156.433742650384;14161.78757318641;11354.716132096764;11452.255603385753;11466.31202127875;11469.770604588028;11480.598179063692;13339.19964802115;13340.399682014333;13360.93319533396;13363.398647060905;13445.260510672008;4277.871663850089;5877.177195192677;5932.377100404482;6004.129401236796;6155.045967874987;903.5301643145527;941.463365881441;945.7919257763281;977.5552123783662;1005.1154983614518;21164.682529762067;22385.552288954128;22902.42743931415;23663.408414789425;24016;1040.869891263592;1046.424466543398;1055.8758903422663;1108.7228192951761;1120.355795417638;10922.29005114665;11542.02304023576;11669.124815638861;11793.88206117945;12156.75686486283;318977;319527;319589;320610;322285;51917.22124168141;52337.73831130896;52491.95016099657;52753;52815.73592132091;1427.5931359562476;1500.7373709600995;1504.9172295523852;1505.826829914552;1508.6379268975368;21476.716751521173;22057.889710551346;22625.547489050456;22675.433114172276;23591.29226123219;36636.633633663325;36962.630373695916;37106.628933710315;37139.44290835586;37220.62779372172;252597;253817;254171;254604;254866;28818.991335303162;29745.405091897606;29763.851180743957;34348.48477272893;34523.30953380968;39558.80220598994;40374.798126010355;40888.59111408967;42979.78510107555;43012.924676882074;27694.72305277002;27695.58456623112;28698.56952145761;29073.27316817012;30203.848980754956;29355.091919025195;29702.405951881272;31535.684643153276;32356;32765.672343276263;38546;38613.648522301584;39368;40004.79985600436;40009;35624.64375356213;36872.26255474929;37370.626293737805;38799.6120038796;39232;26866.355545151913;30724.231894202825;30929.536056958714;31064.068077957698;31253.531197032517 08:47:25 INFO - PID 2618 | |2;jquery.html;476.4853957725656;497.5894886718456;505.22448042255144;506.1673547015159;531.5667730799396;21394.358169254927;21759;21971.340859774213;22140.446488837788;22297.108115675375;23963;24488.265352039467;24747.886345114468;24751.257462276117;24830.25509234721;6286.685665716707;6499.480041596685;6501.902471462909;6590.011498275273;6594.3075977022445;6383.042543618448;6511.902321465182;6514.934850651504;6525.967370163143;6527.902081468782;5446.2919820423285;5499.917501237484;6118.174046503709;6268.216472940882;6323.715432805535;6288.811335659914;6290.748370065215;6330.873382532323;6338.429541341301;6340.524460665469;5765.1352297155345;5805.854853628651;5808.2449281593545;5814.098814683731;5818.709064546798;29868;30027.39945201084;30427.391452171054;30975.38049239025;31346.21634459134;1944.251463186669;2260.9208677696365;2267.9206227782115;2288.5766133265397;2297.9774000569755;18246.087695615166;18435.354762583378;18722;18724.063796810104;18759.155837987324;2160.287105255262;2160.3302976077307;2200.9779902200935;2206.2719302630094;2238.641817309239;141704;165808;170201.14899425546;174212;183516;13368.197908125543;13609.251491168081;13941.930290348484;14781.556553303419;15045.473408430653;16449.83550164483;20243.493912652302;20315.18739250443;20321.187152513732;20331.390058298275;7828.921710782819;9481;9858.30991830565;9882.308238423242;9938.950305248427;8297.792555186048;8332.458390204618;9070.365074444857;9152.496612686366;9218.769530761785;8877.20105190536;9021.548922553846;9034.819303613891;9060;9082.909170908339;2195.6596727507263;2532.6327682485935;2542.7965762738936;2546.5034318307753;2704.91885243443;931.7577429868284;938.6296007828647;941.6374695742188;1000.6347683095763;1007.8387458006683;18422;18547.907260463882;20608.58782824365;20942.267020654202;21570.56858862814;862.9827403452022;964.2141654551518;1024.9590016399409;1027.2963020331097;1028.783955369366;8547.888774459281;10337.948310258551;10810.972957569018;10882.61910833117;11076.944615276872;573384;573780;591129;592046;598325;121442;155111;155439;156207;165198;976.1458723616835;1030.6804890483975;1047.7747284333905;1191.7795207886568;1232.340697726694;21340.466488337916;23185.536289273783;23623.88188059049;23650.645240321064;23676.052957881493;66864;69958.95061574182;70079.6496017537;70555.64722176356;75173.62413188118;97964;98785.01214988045;100557.49721251347;102036.48981755043;102549;11873.287602743863;11987.520499179935;12083.972862306802;12112.576059837864;12152.696182595506;18638.81361186425;18797.812021879607;18846.057697114815;19062.809371906103;19246.133923973735;14405.78391324152;15491.612709681896;15585.831062670119;15589.844101559293;15592;13490.730185396042;13668.658283542592;13774.311284435538;14058.929705351407;14093.929530352283;15601.609959750644;15688.686226275182;15909.60225994313;15926.96474729139;15941.123238221546;11292.927171918654;11503.079753619522;11916.165868389477;11926.761464770829;11935.403229838299;11831.704207394883;14729.0426122306;14830.110193388431;14845.480408185662;14860.479883204036 08:47:25 INFO - PID 2618 | |3;mootools.html;1241.4909886946355;1361.3057340756216;1394.9677238843253;1441.5170917742555;1445.0751519027822;11209.607663731771;12946;13451.991100667452;13662.863371366284;13684.384202710877;13123.868761312384;13399.062065655407;13460.66348341292;13550.864491355083;13581;2657.707652158261;2702.7702645275176;2723.346396864758;2723.536998710214;2724.3053021479495;2644.3521337272396;2682.7719643830296;2694.932626684329;2695.487857307115;2695.9191224263204;2240.215924426446;2667.7465640764194;2701.6352792373073;2707.431439397729;2711.5797051456957;2623.921282361532;2629.3558078270753;2632.1313966391044;2633.5917932720267;2635.354338187147;2257.4469255032623;2600.3369140869145;2625;2645.3254420122744;2648.4173481833964;13237.801432978513;13282;13323.13399629021;13370.39833207508;13382.397792099378;6781.355771201777;7303.853922921512;7313.341799238041;7602.0877494700435;7608.35328997036;4578.908421831578;4718.976405117986;4748.71507709535;4820.758962051918;4822.228443449067;1396.958091257264;1399.8950078744135;1403.8034675145386;1406.2336026865382;1423.8647328503775;18162.727559086623;18926.81073189278;19561.1197496113;19654.606907861904;19743.901280493505;5781.942180578225;5917.289925209003;6962.303769622998;7127.144742630814;7157.463190260752;15992.840071599368;16007.27967241477;16181.271842766868;16188.271527781279;16215.83784162167;7325.4872158948365;7631.847363052708;7638.847223055508;7642.617869106522;7651.502652327581;6751.493637977073;6924.861502769916;6925.376716095576;6929.930700693029;6970.51206415546;5352.9732351338;5376.731163441812;5399.595030372738;5450.1007333789485;5459.208414779835;1641.3926847066662;1681.1510187355307;1694.1359906447801;1709.0685576360913;1717.6736420080144;843.8312337532518;857.6312185760058;866.4974314897345;875.2341701011615;896.2158111652304;7029.789106326818;7107.92892071083;7251.202367739545;7304.379127774092;7322.926770732331;622.996885015572;645.4997377032837;705.8094314535149;715.3418854653731;736.576468530597;4195.475565554306;5175.171972484384;5222.556082732935;5276.457193080552;5496.587755918322;13795.172289662652;17369.7394539082;17591.73612395815;17947;17993.370232041816;14837.62905927339;17530.73703894417;17577.560560986076;18068.457946261635;18333.63332733338;9062.139096785926;9251.074892510695;9497.525123743786;9519.286053546011;9520.809583808285;7128.28717128283;7613.819857922031;7689.538627682357;7738.806529836799;7794.805129871798;12426.875731242571;15701.293441795375;15733.685326293638;15910;16001.919990399974;13854.30728463593;15426.768598470808;16622.75065874037;16675.33298668088;16716;10567.577296908345;10786.083182929538;10791.946040269748;10808.135349172204;10833.187510936554;12243.69390765238;12344.691382715504;12350.938245308716;12368.628941131781;12390.62828115158;9594.616215351307;9690;9854.802903942025;9980.700578982642;10000.849987250343;9471.66849160276;9507.572159252712;9714.902850971399;9868.605255789687;10061.597536098474;9031.006589275306;9098.681546145854;9124.361294709306;9138.543072846463;9146.954265228898;6384.712687929147;6614;6921.8961715575315;6961.79114626562;7023.894641580278;6785.185777706707;6956.443484521327;7124.180719217356;7230.74692385764;7257.963710181415 08:47:25 INFO - PID 2618 | |4;prototype.html;423.5129600958897;441.63123791633984;453.36076132652965;479.79848463645243;480.3851070629593;5324.600654950879;5657.151427285909;6500.544961852666;6518.674066296684;6537.8365540861505;5251.765835028765;6027.849303767408;6218;6277.2153480814895;6393.890016294623;4068.6745060395247;4876.878078048042;5002.12462819007;5014.548690617844;5063.645544811856;4222.1766755482695;4781.6413768967295;5021.347224860763;5026.899462010758;5053.722045287508;4944.354770197945;4950.232713929328;4958.454569997298;4969.602431805447;5015.272785446126;4752.287156926454;4963.627727920402;4965.975170124144;4991.276264941599;4991.950080499221;3620;4302.978485107586;4397.956020439787;4525.800662824354;4529.365888775574;6281.59169653971;6294.213223347082;6307.716152773136;6307.747690092391;6335.429811316957;5120.487951204887;5255.369355677343;5797.971010144965;5864.882702345929;6050.5478685115695;5873.882522349571;6630.535862489628;6824.556403833735;6855.828604284883;6928.653567321614;5239.685618862881;5270.025045366581;5278.621682123444;5296.3379577552805;5306.3632364116165;7266.709331626728;7266.781996540112;7273.036280204368;7283.999999999947;7295.759720847469;5161.354830646169;5404.648697834628;5406.729663516809;5463.508284254436;5486.231927530149;4929.654924155275;5165.509276618715;5544.63959842609;5559.471850174227;5577.944220557823;5725.484706376446;6060.696965151725;6065.877812604682;6071.696415179224;6123.540734444934;6941.652917354112;6944.652767361612;6947.305269473012;6956.686949087303;6976.686049127801;1793.1930631216005;1880.7649043869517;1891.5176629959547;1897.4307707687915;1899.221319259097;1302.7134030513273;1306.5753630070265;1343.603636927109;1348.1035111650838;1353.147517064263;5043.11680006393;6121.785737499166;6421.903671444932;6432.228132624022;6437.839054023593;5957.880842383128;6835.760748373783;6881.277465866112;7065.717371305193;7115.5730656160795;6209.875802483925;6389.169407976933;6389.712462939179;6412.583182093243;6435.613863168225;3666.211764470652;3688.5573731152435;3706.0364305280823;3706.240220754739;3724.925501489955;6049.939500604937;6676.632785196861;7260.636968151677;7279.526830755984;7325.4139668826365;7244.13070431541;7275.798648594601;7280.635968201569;7284.635768211569;7335.48651594394;5701.543876489871;5704.771809127588;5977.894089593439;7255.891161632685;7290.015847860471;7064.788056358318;7083.5041547091205;7092.929070709433;7104.680289387094;7106;6920.273371296143;6961.642479716406;6975.825604359931;6982.79051628452;6986.9650651746415;5313.495217954288;5818.799660186795;6740.056392105207;6926.203486599105;6942.548734332252;3820.656140947328;3846.3461211593535;4842.83050093245;4871.562888947839;4876.512348765095;7047.859042819217;7058.200158959433;7090.326418990187;7091.751788687371;7115;6769.864602708017;6788.26899140716;6797.592144471348;6799.456043516408;6800.489963252677;6218.844528886814;6761.594304341558;6783.18601767791;6786;6797.320267973361;6165.938340616536;6676.899846502403;6685.39831415175;6712.664366781739;6732.596044237362;6363;6741.2921643226755;6747.696353664195;6758.324167583202;6994.545354551938;5605.859853503695;6339.366063393623;6567.638779867153;6623.205215374186;6634.966825166036 08:47:25 INFO - PID 2618 | |5;yui.html;778.4823092643393;849.4648371525938;860.7589874835046;884.5356188001299;884.8274586455642;16688.249028793703;16914.74627880581;17109;17145.485635430956;17178.82821171788;11425.542978280859;12543.18469299495;13189.538366157187;15538.300776465057;16497.91751041243;960.4813400763579;972.610955617755;983.5918093990986;985.522021819417;988.2538683294102;969.6460791810973;1000.1748557440104;1000.2248257600345;1003.618624922532;1003.7653685966266;1024.0629823711295;1047.7537778622018;1051.47951763877;1051.8159322118636;1052.8526006359116;939.685205456173;971.03382134776;1051.2431049644206;1053.4574694032578;1056.7675111475462;768.3507436216388;769.3844924060729;771.6874665760377;771.9459637825355;773.5899973014311;17181.226844792014;17293.221805018806;17380.392941058817;17442.47672569825;17477.825221747746;14666;14886.925565372212;17292.394766183123;17532.474025779247;17547;14183.149011059266;14868.70262594742;14874.553763387115;14909.105453672706;14922.70154596902;3580.480830279621;3606.675399214057;3608.0619039049775;3609.530761001079;3613.981930090359;104025;118101;136610;148590;149990.50009499985;11608.24546404498;13492.5277615283;13582.86417135816;13644.863551364559;13781.86218137826;26173;26430.6035409469;26565.734342656327;26596.20211393661;26800.865995670287;7771.028621422322;7833.255840695125;7842.411819113589;7848.13670496245;7857.9214207857185;3615.493830863681;3738.532683414573;3775.9433608495347;3787;3791.1280405506336;4613.65397595175;4651.813927442932;4686.6485013624115;4697.741624210701;4708.293755936637;876.5617191404298;918.912703293186;921.4010892919656;921.6451666108596;929.6095639831287;572.4533070917264;610.9419605137505;653.6045692356151;665.0323778901686;691.4952084977995;8743.431676940978;11709.126539753599;12089.274643521418;12188.939055304667;12369.958956772212;637.2607774981;652.7356420649644;658.2627457247818;658.555475054346;673.9966300168468;5417.512423881791;5552.694601796859;5625;5673;5900.262467191601;130579;133203;136230.31884840512;137530;140429;124108.37945810214;128566;129253;129517.35241323922;132301.33849330692;110663;113407.29889051671;113955;116895;116927.41536292263;9596.712098637052;11042.227044106836;11092.944535277595;11284.830727539258;11393.803650616566;44286.55713442953;45536.77231613821;46355;46413;47916.28125578189;60657.69671151616;61132.388676114446;63693;64033.35966640274;65029.349706504225;14552.126872387691;14711.411543538567;14878.553643390716;15102.848971510584;15125.941184117446;16061;16245.944013639075;16364.2636081379;16477.258523366716;16672.83327166713;14541.636459088608;14797.112173269637;14845.777313340093;14883.032602880778;15209.543713688165;14552.345144468312;14758.55724328272;14961.102333860003;15034.17312047848;15277.618059548156;14182.503612373517;14353.138811671333;14672.633184170481;14839.629009274855;14858.628534286729;9943.900126422433;10564.207684423853;10810.027097561257;10859.782804343711;11023.007929286403;9285.814283714422;10199.745006374604;10971.067459266053;11387.943060284646;11439.942800285946 08:47:25 INFO - PID 2618 | __end_tp_report 08:47:25 INFO - PID 2618 | __start_cc_report 08:47:25 INFO - PID 2618 | _x_x_mozilla_cycle_collect,476 08:47:25 INFO - PID 2618 | __end_cc_report 08:47:25 INFO - PID 2618 | __startTimestamp1497282445909__endTimestamp 08:47:25 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | ------- Summary: start ------- 08:47:25 INFO - PID 2618 | Number of tests: 6 08:47:25 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | [#0] dojo.html Cycles:165 Average:6053.79 Median:6335.40 stddev:718.81 (11.3%) stddev-sans-first:691.18 08:47:25 INFO - PID 2618 | Values: 3441.0 3490.3 3530.9 3558.4 3577.3 4555.7 6017.4 6049.8 6116.4 6141.9 6062.8 6127.8 6130.0 6135.8 6142.8 5311.6 5322.8 5342.4 5350.2 5356.8 5391.4 5400.2 5433.3 5438.0 5453.1 5362.3 5398.9 5435.8 5440.0 5447.6 5362.0 5381.2 5409.8 5429.5 5430.7 5321.8 5367.0 5379.7 5415.7 5420.8 6236.8 6269.8 6277.7 6331.6 6341.2 6372.6 6447.6 6584.6 6591.2 6593.4 6538.8 6539.6 6616.3 6679.0 6702.4 6341.2 6361.6 6373.6 6374.0 6382.0 6827.0 6866.7 6877.3 6887.3 6901.5 6482.1 6502.8 6505.2 6513.8 6530.6 5806.6 6420.6 6497.2 6512.0 6520.0 5915.0 6528.0 6557.0 6564.9 6607.9 6219.2 6321.4 6563.0 6581.6 6607.8 5192.9 5194.0 5224.8 5231.4 5283.9 4684.0 4710.2 4722.9 4729.2 4731.9 6184.6 6268.1 6330.0 6335.4 6352.0 6641.1 6702.6 6728.2 6728.6 6735.1 5316.5 5327.1 5357.9 6278.1 6320.9 5646.7 5694.0 5809.1 5842.8 5858.9 6659.6 6688.7 6689.3 6706.6 6727.8 5733.3 6483.1 6657.5 6661.1 6694.4 6786.8 6788.7 6800.4 6807.3 6808.1 6543.1 6555.8 6566.4 6584.3 6599.1 6451.3 6549.8 6652.4 6666.5 6669.3 6348.2 6353.2 6454.4 6464.4 6471.3 6272.5 6345.7 6372.0 6376.5 6385.6 5509.7 5642.9 6301.7 6313.2 6348.1 6345.5 6370.7 6371.6 6374.5 6379.4 6241.6 6269.1 6283.9 6312.4 6321.9 08:47:25 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | [#1] ext.html Cycles:180 Average:43035.85 Median:18938.29 stddev:73368.13 (387.4%) stddev-sans-first:73505.16 08:47:25 INFO - PID 2618 | Values: 721.4 727.9 809.6 831.2 1045.6 34430.5 38261.0 38320.8 38377.0 38420.0 38334.8 38526.6 38572.8 38615.2 38730.0 7691.5 7833.9 7856.6 7856.8 7865.3 7700.2 7770.3 7790.0 7812.3 7980.6 6262.3 7762.8 8228.5 8271.1 8283.7 7614.2 7758.4 8108.1 8227.8 8237.5 7989.4 7999.7 8041.2 8043.7 8062.7 35768.1 35861.3 35898.0 35926.0 35970.8 3357.0 3366.5 3396.7 3398.7 3446.1 28747.4 28875.0 29002.0 29003.7 29452.7 2953.1 2994.0 3008.5 3024.1 3070.9 243515.0 248098.0 248784.0 281437.6 283545.0 35989.0 38072.5 46088.0 46272.0 46537.8 49174.0 49444.5 49692.0 49800.5 49819.3 14047.5 14073.3 14095.7 14156.4 14161.8 11354.7 11452.3 11466.3 11469.8 11480.6 13339.2 13340.4 13360.9 13363.4 13445.3 4277.9 5877.2 5932.4 6004.1 6155.0 903.5 941.5 945.8 977.6 1005.1 21164.7 22385.6 22902.4 23663.4 24016.0 1040.9 1046.4 1055.9 1108.7 1120.4 10922.3 11542.0 11669.1 11793.9 12156.8 318977.0 319527.0 319589.0 320610.0 322285.0 51917.2 52337.7 52492.0 52753.0 52815.7 1427.6 1500.7 1504.9 1505.8 1508.6 21476.7 22057.9 22625.5 22675.4 23591.3 36636.6 36962.6 37106.6 37139.4 37220.6 252597.0 253817.0 254171.0 254604.0 254866.0 28819.0 29745.4 29763.9 34348.5 34523.3 39558.8 40374.8 40888.6 42979.8 43012.9 27694.7 27695.6 28698.6 29073.3 30203.8 29355.1 29702.4 31535.7 32356.0 32765.7 38546.0 38613.6 39368.0 40004.8 40009.0 35624.6 36872.3 37370.6 38799.6 39232.0 26866.4 30724.2 30929.5 31064.1 31253.5 08:47:25 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | [#2] jquery.html Cycles:180 Average:39657.94 Median:12173.36 stddev:100090.01 (822.2%) stddev-sans-first:100327.55 08:47:25 INFO - PID 2618 | Values: 476.5 497.6 505.2 506.2 531.6 21394.4 21759.0 21971.3 22140.4 22297.1 23963.0 24488.3 24747.9 24751.3 24830.3 6286.7 6499.5 6501.9 6590.0 6594.3 6383.0 6511.9 6514.9 6526.0 6527.9 5446.3 5499.9 6118.2 6268.2 6323.7 6288.8 6290.7 6330.9 6338.4 6340.5 5765.1 5805.9 5808.2 5814.1 5818.7 29868.0 30027.4 30427.4 30975.4 31346.2 1944.3 2260.9 2267.9 2288.6 2298.0 18246.1 18435.4 18722.0 18724.1 18759.2 2160.3 2160.3 2201.0 2206.3 2238.6 141704.0 165808.0 170201.1 174212.0 183516.0 13368.2 13609.3 13941.9 14781.6 15045.5 16449.8 20243.5 20315.2 20321.2 20331.4 7828.9 9481.0 9858.3 9882.3 9939.0 8297.8 8332.5 9070.4 9152.5 9218.8 8877.2 9021.5 9034.8 9060.0 9082.9 2195.7 2532.6 2542.8 2546.5 2704.9 931.8 938.6 941.6 1000.6 1007.8 18422.0 18547.9 20608.6 20942.3 21570.6 863.0 964.2 1025.0 1027.3 1028.8 8547.9 10337.9 10811.0 10882.6 11076.9 573384.0 573780.0 591129.0 592046.0 598325.0 121442.0 155111.0 155439.0 156207.0 165198.0 976.1 1030.7 1047.8 1191.8 1232.3 21340.5 23185.5 23623.9 23650.6 23676.1 66864.0 69959.0 70079.6 70555.6 75173.6 97964.0 98785.0 100557.5 102036.5 102549.0 11873.3 11987.5 12084.0 12112.6 12152.7 18638.8 18797.8 18846.1 19062.8 19246.1 14405.8 15491.6 15585.8 15589.8 15592.0 13490.7 13668.7 13774.3 14058.9 14093.9 15601.6 15688.7 15909.6 15927.0 15941.1 11292.9 11503.1 11916.2 11926.8 11935.4 11831.7 14729.0 14830.1 14845.5 14860.5 08:47:25 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | [#3] mootools.html Cycles:180 Average:8135.27 Median:2702.20 stddev:5300.95 (196.2%) stddev-sans-first:5290.50 08:47:25 INFO - PID 2618 | Values: 1241.5 1361.3 1395.0 1441.5 1445.1 11209.6 12946.0 13452.0 13662.9 13684.4 13123.9 13399.1 13460.7 13550.9 13581.0 2657.7 2702.8 2723.3 2723.5 2724.3 2644.4 2682.8 2694.9 2695.5 2695.9 2240.2 2667.7 2701.6 2707.4 2711.6 2623.9 2629.4 2632.1 2633.6 2635.4 2257.4 2600.3 2625.0 2645.3 2648.4 13237.8 13282.0 13323.1 13370.4 13382.4 6781.4 7303.9 7313.3 7602.1 7608.4 4578.9 4719.0 4748.7 4820.8 4822.2 1397.0 1399.9 1403.8 1406.2 1423.9 18162.7 18926.8 19561.1 19654.6 19743.9 5781.9 5917.3 6962.3 7127.1 7157.5 15992.8 16007.3 16181.3 16188.3 16215.8 7325.5 7631.8 7638.8 7642.6 7651.5 6751.5 6924.9 6925.4 6929.9 6970.5 5353.0 5376.7 5399.6 5450.1 5459.2 1641.4 1681.2 1694.1 1709.1 1717.7 843.8 857.6 866.5 875.2 896.2 7029.8 7107.9 7251.2 7304.4 7322.9 623.0 645.5 705.8 715.3 736.6 4195.5 5175.2 5222.6 5276.5 5496.6 13795.2 17369.7 17591.7 17947.0 17993.4 14837.6 17530.7 17577.6 18068.5 18333.6 9062.1 9251.1 9497.5 9519.3 9520.8 7128.3 7613.8 7689.5 7738.8 7794.8 12426.9 15701.3 15733.7 15910.0 16001.9 13854.3 15426.8 16622.8 16675.3 16716.0 10567.6 10786.1 10791.9 10808.1 10833.2 12243.7 12344.7 12350.9 12368.6 12390.6 9594.6 9690.0 9854.8 9980.7 10000.8 9471.7 9507.6 9714.9 9868.6 10061.6 9031.0 9098.7 9124.4 9138.5 9147.0 6384.7 6614.0 6921.9 6961.8 7023.9 6785.2 6956.4 7124.2 7230.7 7258.0 08:47:25 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | [#4] prototype.html Cycles:180 Average:5638.63 Median:6187.91 stddev:1648.98 (26.6%) stddev-sans-first:1606.48 08:47:25 INFO - PID 2618 | Values: 423.5 441.6 453.4 479.8 480.4 5324.6 5657.2 6500.5 6518.7 6537.8 5251.8 6027.8 6218.0 6277.2 6393.9 4068.7 4876.9 5002.1 5014.5 5063.6 4222.2 4781.6 5021.3 5026.9 5053.7 4944.4 4950.2 4958.5 4969.6 5015.3 4752.3 4963.6 4966.0 4991.3 4992.0 3620.0 4303.0 4398.0 4525.8 4529.4 6281.6 6294.2 6307.7 6307.7 6335.4 5120.5 5255.4 5798.0 5864.9 6050.5 5873.9 6630.5 6824.6 6855.8 6928.7 5239.7 5270.0 5278.6 5296.3 5306.4 7266.7 7266.8 7273.0 7284.0 7295.8 5161.4 5404.6 5406.7 5463.5 5486.2 4929.7 5165.5 5544.6 5559.5 5577.9 5725.5 6060.7 6065.9 6071.7 6123.5 6941.7 6944.7 6947.3 6956.7 6976.7 1793.2 1880.8 1891.5 1897.4 1899.2 1302.7 1306.6 1343.6 1348.1 1353.1 5043.1 6121.8 6421.9 6432.2 6437.8 5957.9 6835.8 6881.3 7065.7 7115.6 6209.9 6389.2 6389.7 6412.6 6435.6 3666.2 3688.6 3706.0 3706.2 3724.9 6049.9 6676.6 7260.6 7279.5 7325.4 7244.1 7275.8 7280.6 7284.6 7335.5 5701.5 5704.8 5977.9 7255.9 7290.0 7064.8 7083.5 7092.9 7104.7 7106.0 6920.3 6961.6 6975.8 6982.8 6987.0 5313.5 5818.8 6740.1 6926.2 6942.5 3820.7 3846.3 4842.8 4871.6 4876.5 7047.9 7058.2 7090.3 7091.8 7115.0 6769.9 6788.3 6797.6 6799.5 6800.5 6218.8 6761.6 6783.2 6786.0 6797.3 6165.9 6676.9 6685.4 6712.7 6732.6 6363.0 6741.3 6747.7 6758.3 6994.5 5605.9 6339.4 6567.6 6623.2 6635.0 08:47:25 INFO - PID 2618 | 08:47:25 INFO - PID 2618 | [#5] yui.html Cycles:180 Average:24744.37 Median:16801.50 stddev:38707.49 (230.4%) stddev-sans-first:38774.24 08:47:25 INFO - PID 2618 | Values: 778.5 849.5 860.8 884.5 884.8 16688.2 16914.7 17109.0 17145.5 17178.8 11425.5 12543.2 13189.5 15538.3 16497.9 960.5 972.6 983.6 985.5 988.3 969.6 1000.2 1000.2 1003.6 1003.8 1024.1 1047.8 1051.5 1051.8 1052.9 939.7 971.0 1051.2 1053.5 1056.8 768.4 769.4 771.7 771.9 773.6 17181.2 17293.2 17380.4 17442.5 17477.8 14666.0 14886.9 17292.4 17532.5 17547.0 14183.1 14868.7 14874.6 14909.1 14922.7 3580.5 3606.7 3608.1 3609.5 3614.0 104025.0 118101.0 136610.0 148590.0 149990.5 11608.2 13492.5 13582.9 13644.9 13781.9 26173.0 26430.6 26565.7 26596.2 26800.9 7771.0 7833.3 7842.4 7848.1 7857.9 3615.5 3738.5 3775.9 3787.0 3791.1 4613.7 4651.8 4686.6 4697.7 4708.3 876.6 918.9 921.4 921.6 929.6 572.5 610.9 653.6 665.0 691.5 8743.4 11709.1 12089.3 12188.9 12370.0 637.3 652.7 658.3 658.6 674.0 5417.5 5552.7 5625.0 5673.0 5900.3 130579.0 133203.0 136230.3 137530.0 140429.0 124108.4 128566.0 129253.0 129517.4 132301.3 110663.0 113407.3 113955.0 116895.0 116927.4 9596.7 11042.2 11092.9 11284.8 11393.8 44286.6 45536.8 46355.0 46413.0 47916.3 60657.7 61132.4 63693.0 64033.4 65029.3 14552.1 14711.4 14878.6 15102.8 15125.9 16061.0 16245.9 16364.3 16477.3 16672.8 14541.6 14797.1 14845.8 14883.0 15209.5 14552.3 14758.6 14961.1 15034.2 15277.6 14182.5 14353.1 14672.6 14839.6 14858.6 9943.9 10564.2 10810.0 10859.8 11023.0 9285.8 10199.7 10971.1 11387.9 11439.9 08:47:25 INFO - PID 2618 | -------- Summary: end -------- 08:47:25 INFO - PID 2618 | 08:47:26 INFO - TEST-INFO | 2618: exit 0 08:47:26 INFO - TEST-OK | dromaeo_css | took 1143674ms 08:47:26 INFO - TEST-START | kraken 08:47:26 INFO - operating with platform_type : mac_ 08:47:26 INFO - Initialising browser for kraken test... 08:47:26 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:50262/getInfo.html -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp1yNVQW/profile 08:47:26 INFO - TEST-INFO | started process 2625 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:50262/getInfo.html -foreground) 08:48:00 INFO - TEST-INFO | 2625: killed by SIGTERM 08:48:00 INFO - Browser initialized. 08:48:00 INFO - Running cycle 1/1 for kraken test... 08:48:00 INFO - TEST-INFO | started process 2629 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp1yNVQW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:02 INFO - PID 2629 | 2017-06-12 08:48:02.144 plugin-container[2630:25703] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 08:48:02 INFO - PID 2629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:02 INFO - PID 2629 | 2017-06-12 08:48:02.145 plugin-container[2630:25703] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 08:48:02 INFO - PID 2629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:02 INFO - PID 2629 | 2017-06-12 08:48:02.145 plugin-container[2630:25703] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:48:02 INFO - PID 2629 | 2017-06-12 08:48:02.886 plugin-container[2631:25751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 08:48:02 INFO - PID 2629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:02 INFO - PID 2629 | 2017-06-12 08:48:02.887 plugin-container[2631:25751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 08:48:02 INFO - PID 2629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:02 INFO - PID 2629 | 2017-06-12 08:48:02.887 plugin-container[2631:25751] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:48:12 INFO - PID 2629 | RSS: Main: 252944384 08:48:12 INFO - PID 2629 | 08:48:15 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50262/tests/kraken/driver.html?testName=audio-beat-detection) 08:48:16 INFO - PID 2629 | RSS: Main: 265052160 08:48:16 INFO - PID 2629 | 08:48:18 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50262/tests/kraken/driver.html?testName=audio-dft) 08:48:19 INFO - PID 2629 | RSS: Main: 275644416 08:48:19 INFO - PID 2629 | 08:48:23 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50262/tests/kraken/driver.html?testName=audio-fft) 08:48:23 INFO - PID 2629 | RSS: Main: 278814720 08:48:23 INFO - PID 2629 | 08:48:25 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50262/tests/kraken/driver.html?testName=audio-oscillator) 08:48:26 INFO - PID 2629 | RSS: Main: 280768512 08:48:26 INFO - PID 2629 | 08:48:28 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:48:29 INFO - PID 2629 | RSS: Main: 281190400 08:48:29 INFO - PID 2629 | 08:48:32 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50262/tests/kraken/driver.html?testName=imaging-darkroom) 08:48:32 INFO - PID 2629 | RSS: Main: 285491200 08:48:32 INFO - PID 2629 | 08:48:35 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50262/tests/kraken/driver.html?testName=imaging-desaturate) 08:48:36 INFO - PID 2629 | RSS: Main: 286408704 08:48:36 INFO - PID 2629 | 08:48:39 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50262/tests/kraken/driver.html?testName=json-parse-financial) 08:48:40 INFO - PID 2629 | RSS: Main: 286011392 08:48:40 INFO - PID 2629 | 08:48:41 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:48:42 INFO - PID 2629 | RSS: Main: 286081024 08:48:42 INFO - PID 2629 | 08:48:44 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50262/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:48:44 INFO - PID 2629 | RSS: Main: 285888512 08:48:44 INFO - PID 2629 | 08:48:46 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:48:47 INFO - PID 2629 | RSS: Main: 285843456 08:48:47 INFO - PID 2629 | 08:48:50 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:48:51 INFO - PID 2629 | RSS: Main: 286081024 08:48:51 INFO - PID 2629 | 08:48:54 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:48:54 INFO - PID 2629 | RSS: Main: 286007296 08:48:54 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | Cycle 1(1): loaded http://localhost:50262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50262/tests/kraken/driver.html?testName=ai-astar) 08:48:56 INFO - PID 2629 | RSS: Main: 287825920 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | __start_tp_report 08:48:56 INFO - PID 2629 | _x_x_mozilla_page_load 08:48:56 INFO - PID 2629 | _x_x_mozilla_page_load_details 08:48:56 INFO - PID 2629 | |i|pagename|runs| 08:48:56 INFO - PID 2629 | |0;ai-astar;104;114;115;129;159;176;145;117;139;151 08:48:56 INFO - PID 2629 | |1;audio-beat-detection;142;116;171;163;135;164;174;154;161;110 08:48:56 INFO - PID 2629 | |2;audio-dft;203;204;216;291;229;221;248;282;201;204 08:48:56 INFO - PID 2629 | |3;audio-fft;70;100;102;131;55;60;95;61;93;50 08:48:56 INFO - PID 2629 | |4;audio-oscillator;71;68;114;100;93;93;111;73;76;109 08:48:56 INFO - PID 2629 | |5;imaging-gaussian-blur;89;94;136;123;89;88;89;90;89;89 08:48:56 INFO - PID 2629 | |6;imaging-darkroom;98;95;157;116;96;97;95;96;98;110 08:48:56 INFO - PID 2629 | |7;imaging-desaturate;98;91;91;94;93;91;92;91;92;91 08:48:56 INFO - PID 2629 | |8;json-parse-financial;50;50;49;50;53;55;62;78;55;55 08:48:56 INFO - PID 2629 | |9;json-stringify-tinderbox;57;58;65;82;80;63;55;71;53;51 08:48:56 INFO - PID 2629 | |10;stanford-crypto-aes;84;80;78;82;81;79;82;82;75;94 08:48:56 INFO - PID 2629 | |11;stanford-crypto-ccm;138;157;138;139;138;139;146;138;161;159 08:48:56 INFO - PID 2629 | |12;stanford-crypto-pbkdf2;177;182;181;183;165;211;237;199;182;176 08:48:56 INFO - PID 2629 | |13;stanford-crypto-sha256-iterative;69;68;68;69;66;69;64;68;63;75 08:48:56 INFO - PID 2629 | __end_tp_report 08:48:56 INFO - PID 2629 | __start_cc_report 08:48:56 INFO - PID 2629 | _x_x_mozilla_cycle_collect,1193 08:48:56 INFO - PID 2629 | __end_cc_report 08:48:56 INFO - PID 2629 | __startTimestamp1497282536780__endTimestamp 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | ------- Summary: start ------- 08:48:56 INFO - PID 2629 | Number of tests: 14 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#0] ai-astar Cycles:10 Average:134.90 Median:142.00 stddev:23.07 (16.2%) stddev-sans-first:21.59 08:48:56 INFO - PID 2629 | Values: 104.0 114.0 115.0 129.0 159.0 176.0 145.0 117.0 139.0 151.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#1] audio-beat-detection Cycles:10 Average:149.00 Median:162.00 stddev:22.49 (13.9%) stddev-sans-first:23.72 08:48:56 INFO - PID 2629 | Values: 142.0 116.0 171.0 163.0 135.0 164.0 174.0 154.0 161.0 110.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#2] audio-dft Cycles:10 Average:229.90 Median:225.00 stddev:33.25 (14.8%) stddev-sans-first:33.81 08:48:56 INFO - PID 2629 | Values: 203.0 204.0 216.0 291.0 229.0 221.0 248.0 282.0 201.0 204.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#3] audio-fft Cycles:10 Average:81.70 Median:60.50 stddev:26.32 (43.5%) stddev-sans-first:27.58 08:48:56 INFO - PID 2629 | Values: 70.0 100.0 102.0 131.0 55.0 60.0 95.0 61.0 93.0 50.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:72.00 stddev:17.70 (24.6%) stddev-sans-first:17.26 08:48:56 INFO - PID 2629 | Values: 71.0 68.0 114.0 100.0 93.0 93.0 111.0 73.0 76.0 109.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#5] imaging-gaussian-blur Cycles:10 Average:97.60 Median:89.00 stddev:17.17 (19.3%) stddev-sans-first:17.92 08:48:56 INFO - PID 2629 | Values: 89.0 94.0 136.0 123.0 89.0 88.0 89.0 90.0 89.0 89.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#6] imaging-darkroom Cycles:10 Average:105.80 Median:96.00 stddev:19.34 (20.1%) stddev-sans-first:20.31 08:48:56 INFO - PID 2629 | Values: 98.0 95.0 157.0 116.0 96.0 97.0 95.0 96.0 98.0 110.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:92.00 stddev:2.22 (2.4%) stddev-sans-first:1.09 08:48:56 INFO - PID 2629 | Values: 98.0 91.0 91.0 94.0 93.0 91.0 92.0 91.0 92.0 91.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:55.00 stddev:8.74 (15.9%) stddev-sans-first:9.03 08:48:56 INFO - PID 2629 | Values: 50.0 50.0 49.0 50.0 53.0 55.0 62.0 78.0 55.0 55.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#9] json-stringify-tinderbox Cycles:10 Average:63.50 Median:64.00 stddev:10.98 (17.2%) stddev-sans-first:11.39 08:48:56 INFO - PID 2629 | Values: 57.0 58.0 65.0 82.0 80.0 63.0 55.0 71.0 53.0 51.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.00 stddev:5.01 (6.1%) stddev-sans-first:5.25 08:48:56 INFO - PID 2629 | Values: 84.0 80.0 78.0 82.0 81.0 79.0 82.0 82.0 75.0 94.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:142.50 stddev:9.80 (6.9%) stddev-sans-first:10.03 08:48:56 INFO - PID 2629 | Values: 138.0 157.0 138.0 139.0 138.0 139.0 146.0 138.0 161.0 159.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.30 Median:182.50 stddev:21.01 (11.5%) stddev-sans-first:21.81 08:48:56 INFO - PID 2629 | Values: 177.0 182.0 181.0 183.0 165.0 211.0 237.0 199.0 182.0 176.0 08:48:56 INFO - PID 2629 | 08:48:56 INFO - PID 2629 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.50 stddev:3.28 (4.8%) stddev-sans-first:3.46 08:48:56 INFO - PID 2629 | Values: 69.0 68.0 68.0 69.0 66.0 69.0 64.0 68.0 63.0 75.0 08:48:56 INFO - PID 2629 | -------- Summary: end -------- 08:48:56 INFO - PID 2629 | 08:48:57 INFO - TEST-INFO | 2629: exit 0 08:48:57 INFO - TEST-OK | kraken | took 91186ms 08:48:57 INFO - SUITE-END | took 1234s 08:48:58 INFO - Completed test suite (00:20:35) 08:48:58 INFO - javascript benchmark 08:48:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8229.76217598228, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.0365097772633, 3490.319387719395, 3530.858765649374, 3558.4128618777904, 3577.3381924344017, 4555.74943378114, 6017.4283443072945, 6049.818505444839, 6116.366205733227, 6141.915626108638, 6062.757489700418, 6127.830981140916, 6129.969350153254, 6135.815925522242, 6142.81571552853, 5311.62818602698, 5322.76047577858, 5342.385625653042, 5350.224217488471, 5356.785728570872, 5391.353037635469, 5400.162974738922, 5433.293671822677, 5438.048341540215, 5453.127499600065, 5362.329708786402, 5398.865028374303, 5435.83692489226, 5440.047991601474, 5447.564194864422, 5362.0, 5381.165919282498, 5409.837704868841, 5429.484199001128, 5430.70131142787, 5321.840344789663, 5367.0, 5379.67721936685, 5415.729213539307, 5420.810271640474, 6236.750529978841, 6269.811905642838, 6277.686115694197, 6331.588446750947, 6341.207349081365, 6372.649504277263, 6447.613143211375, 6584.604923704593, 6591.209054913441, 6593.3736295051895, 6538.771143009972, 6539.574927629689, 6616.338366163345, 6679.031540426611, 6702.430293425016, 6341.207349081365, 6361.61830290184, 6373.649449280288, 6374.012028135693, 6381.968090159519, 6827.0, 6866.725330986805, 6877.277885822016, 6887.276835932255, 6901.482388820858, 6482.123526506679, 6502.804915852532, 6505.21937367519, 6513.804585862432, 6530.575512591667, 5806.564507661942, 6420.550561460746, 6497.18785151856, 6512.023196520559, 6519.967400163064, 5915.032518357907, 6528.0, 6557.049227862029, 6564.901526477107, 6607.900881486781, 6219.222597175353, 6321.367863213642, 6563.0, 6581.572197807223, 6607.834804129935, 5192.870178245498, 5194.0, 5224.791008359623, 5231.424543300234, 5283.867903302448, 4684.0, 4710.246360582359, 4722.858314250578, 4729.196036673774, 4731.929021064687, 6184.628922264678, 6268.091126786681, 6329.968350158219, 6335.3981371769605, 6351.951063489459, 6641.136652235178, 6702.631355275506, 6728.226253980757, 6728.629925354153, 6735.057092007123, 5316.468353164652, 5327.121025030827, 5357.919631205535, 6278.058291256348, 6320.936790632035, 5646.717664116778, 5693.971530142323, 5809.099589563624, 5842.824715258549, 5858.912116318259, 6659.567128136656, 6688.7324507019175, 6689.26418094019, 6706.59760414367, 6727.764528241488, 5733.283339582552, 6483.059956306215, 6657.500687448364, 6661.067450557023, 6694.397504224448, 6786.830329241809, 6788.694508747019, 6800.387965082969, 6807.251202367636, 6808.080909077211, 6543.051257567721, 6555.80332590023, 6566.409023187936, 6584.275729669828, 6599.109120268894, 6451.3226111257645, 6549.803505894831, 6652.368025037614, 6666.466682665472, 6669.2997235289595, 6348.238211414659, 6353.174087368612, 6454.354564543508, 6464.385883341075, 6471.25580558223, 6272.4982001440685, 6345.7144428499905, 6372.012338087604, 6376.52176086786, 6385.616862988236, 5509.732761464806, 5642.858928526655, 6301.6534090625455, 6313.2424109107205, 6348.111264423077, 6345.4923606112325, 6370.6814659267775, 6371.585846919936, 6374.490040796818, 6379.362063793584, 6241.594296370721, 6269.059641053878, 6283.937160628518, 6312.368763123651, 6321.905171422341], "value": 6006.884203189225, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.4120491799184, 727.8617062758076, 809.6032943857509, 831.2186544648031, 1045.634028090169, 34430.48354274685, 38261.04347391317, 38320.80839595802, 38377.0, 38420.0, 38334.8083259584, 38526.61473385265, 38572.80713596428, 38615.22769544614, 38730.03174920622, 7691.461597688178, 7833.882491762628, 7856.567888766115, 7856.7642970710685, 7865.292123708865, 7700.229977002311, 7770.33952114071, 7790.026246719161, 7812.296893279604, 7980.600969951508, 6262.342454042306, 7762.805929851771, 8228.547429891383, 8271.131531189229, 8283.668653253862, 7614.238576142397, 7758.418118641096, 8108.067572229181, 8227.835443291131, 8237.546934918577, 7989.400794940345, 7999.680012799481, 8041.19588041197, 8043.718469853586, 8062.677492900277, 35768.10579735501, 35861.282774344625, 35898.0, 35926.0, 35970.8201458991, 3357.0, 3366.5455163553097, 3396.728261739055, 3398.7111095556907, 3446.0867870014467, 28747.425051498853, 28875.0, 29002.0, 29003.709962900313, 29452.70547294521, 2953.143588359373, 2993.9700602994126, 3008.4885569453027, 3024.123004328742, 3070.9232269193226, 243515.0, 248098.0, 248784.0, 281437.5928120367, 283545.0, 35989.0, 38072.477100915654, 46088.0, 46272.0, 46537.83655408642, 49174.0, 49444.505554944706, 49692.0061598766, 49800.50199497959, 49819.25271120936, 14047.508337208148, 14073.2963351832, 14095.71808563823, 14156.433742650384, 14161.78757318641, 11354.716132096764, 11452.255603385753, 11466.31202127875, 11469.770604588028, 11480.598179063692, 13339.19964802115, 13340.399682014333, 13360.93319533396, 13363.398647060905, 13445.260510672008, 4277.871663850089, 5877.177195192677, 5932.377100404482, 6004.129401236796, 6155.045967874987, 903.5301643145527, 941.463365881441, 945.7919257763281, 977.5552123783662, 1005.1154983614518, 21164.682529762067, 22385.552288954128, 22902.42743931415, 23663.408414789425, 24016.0, 1040.869891263592, 1046.424466543398, 1055.8758903422663, 1108.7228192951761, 1120.355795417638, 10922.29005114665, 11542.02304023576, 11669.124815638861, 11793.88206117945, 12156.75686486283, 318977.0, 319527.0, 319589.0, 320610.0, 322285.0, 51917.22124168141, 52337.73831130896, 52491.95016099657, 52753.0, 52815.73592132091, 1427.5931359562476, 1500.7373709600995, 1504.9172295523852, 1505.826829914552, 1508.6379268975368, 21476.716751521173, 22057.889710551346, 22625.547489050456, 22675.433114172276, 23591.29226123219, 36636.633633663325, 36962.630373695916, 37106.628933710315, 37139.44290835586, 37220.62779372172, 252597.0, 253817.0, 254171.0, 254604.0, 254866.0, 28818.991335303162, 29745.405091897606, 29763.851180743957, 34348.48477272893, 34523.30953380968, 39558.80220598994, 40374.798126010355, 40888.59111408967, 42979.78510107555, 43012.924676882074, 27694.72305277002, 27695.58456623112, 28698.56952145761, 29073.27316817012, 30203.848980754956, 29355.091919025195, 29702.405951881272, 31535.684643153276, 32356.0, 32765.672343276263, 38546.0, 38613.648522301584, 39368.0, 40004.79985600436, 40009.0, 35624.64375356213, 36872.26255474929, 37370.626293737805, 38799.6120038796, 39232.0, 26866.355545151913, 30724.231894202825, 30929.536056958714, 31064.068077957698, 31253.531197032517], "value": 16992.636555109813, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.4853957725656, 497.5894886718456, 505.22448042255144, 506.1673547015159, 531.5667730799396, 21394.358169254927, 21759.0, 21971.340859774213, 22140.446488837788, 22297.108115675375, 23963.0, 24488.265352039467, 24747.886345114468, 24751.257462276117, 24830.25509234721, 6286.685665716707, 6499.480041596685, 6501.902471462909, 6590.011498275273, 6594.3075977022445, 6383.042543618448, 6511.902321465182, 6514.934850651504, 6525.967370163143, 6527.902081468782, 5446.2919820423285, 5499.917501237484, 6118.174046503709, 6268.216472940882, 6323.715432805535, 6288.811335659914, 6290.748370065215, 6330.873382532323, 6338.429541341301, 6340.524460665469, 5765.1352297155345, 5805.854853628651, 5808.2449281593545, 5814.098814683731, 5818.709064546798, 29868.0, 30027.39945201084, 30427.391452171054, 30975.38049239025, 31346.21634459134, 1944.251463186669, 2260.9208677696365, 2267.9206227782115, 2288.5766133265397, 2297.9774000569755, 18246.087695615166, 18435.354762583378, 18722.0, 18724.063796810104, 18759.155837987324, 2160.287105255262, 2160.3302976077307, 2200.9779902200935, 2206.2719302630094, 2238.641817309239, 141704.0, 165808.0, 170201.14899425546, 174212.0, 183516.0, 13368.197908125543, 13609.251491168081, 13941.930290348484, 14781.556553303419, 15045.473408430653, 16449.83550164483, 20243.493912652302, 20315.18739250443, 20321.187152513732, 20331.390058298275, 7828.921710782819, 9481.0, 9858.30991830565, 9882.308238423242, 9938.950305248427, 8297.792555186048, 8332.458390204618, 9070.365074444857, 9152.496612686366, 9218.769530761785, 8877.20105190536, 9021.548922553846, 9034.819303613891, 9060.0, 9082.909170908339, 2195.6596727507263, 2532.6327682485935, 2542.7965762738936, 2546.5034318307753, 2704.91885243443, 931.7577429868284, 938.6296007828647, 941.6374695742188, 1000.6347683095763, 1007.8387458006683, 18422.0, 18547.907260463882, 20608.58782824365, 20942.267020654202, 21570.56858862814, 862.9827403452022, 964.2141654551518, 1024.9590016399409, 1027.2963020331097, 1028.783955369366, 8547.888774459281, 10337.948310258551, 10810.972957569018, 10882.61910833117, 11076.944615276872, 573384.0, 573780.0, 591129.0, 592046.0, 598325.0, 121442.0, 155111.0, 155439.0, 156207.0, 165198.0, 976.1458723616835, 1030.6804890483975, 1047.7747284333905, 1191.7795207886568, 1232.340697726694, 21340.466488337916, 23185.536289273783, 23623.88188059049, 23650.645240321064, 23676.052957881493, 66864.0, 69958.95061574182, 70079.6496017537, 70555.64722176356, 75173.62413188118, 97964.0, 98785.01214988045, 100557.49721251347, 102036.48981755043, 102549.0, 11873.287602743863, 11987.520499179935, 12083.972862306802, 12112.576059837864, 12152.696182595506, 18638.81361186425, 18797.812021879607, 18846.057697114815, 19062.809371906103, 19246.133923973735, 14405.78391324152, 15491.612709681896, 15585.831062670119, 15589.844101559293, 15592.0, 13490.730185396042, 13668.658283542592, 13774.311284435538, 14058.929705351407, 14093.929530352283, 15601.609959750644, 15688.686226275182, 15909.60225994313, 15926.96474729139, 15941.123238221546, 11292.927171918654, 11503.079753619522, 11916.165868389477, 11926.761464770829, 11935.403229838299, 11831.704207394883, 14729.0426122306, 14830.110193388431, 14845.480408185662, 14860.479883204036], "value": 11635.465737805638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1241.4909886946355, 1361.3057340756216, 1394.9677238843253, 1441.5170917742555, 1445.0751519027822, 11209.607663731771, 12946.0, 13451.991100667452, 13662.863371366284, 13684.384202710877, 13123.868761312384, 13399.062065655407, 13460.66348341292, 13550.864491355083, 13581.0, 2657.707652158261, 2702.7702645275176, 2723.346396864758, 2723.536998710214, 2724.3053021479495, 2644.3521337272396, 2682.7719643830296, 2694.932626684329, 2695.487857307115, 2695.9191224263204, 2240.215924426446, 2667.7465640764194, 2701.6352792373073, 2707.431439397729, 2711.5797051456957, 2623.921282361532, 2629.3558078270753, 2632.1313966391044, 2633.5917932720267, 2635.354338187147, 2257.4469255032623, 2600.3369140869145, 2625.0, 2645.3254420122744, 2648.4173481833964, 13237.801432978513, 13282.0, 13323.13399629021, 13370.39833207508, 13382.397792099378, 6781.355771201777, 7303.853922921512, 7313.341799238041, 7602.0877494700435, 7608.35328997036, 4578.908421831578, 4718.976405117986, 4748.71507709535, 4820.758962051918, 4822.228443449067, 1396.958091257264, 1399.8950078744135, 1403.8034675145386, 1406.2336026865382, 1423.8647328503775, 18162.727559086623, 18926.81073189278, 19561.1197496113, 19654.606907861904, 19743.901280493505, 5781.942180578225, 5917.289925209003, 6962.303769622998, 7127.144742630814, 7157.463190260752, 15992.840071599368, 16007.27967241477, 16181.271842766868, 16188.271527781279, 16215.83784162167, 7325.4872158948365, 7631.847363052708, 7638.847223055508, 7642.617869106522, 7651.502652327581, 6751.493637977073, 6924.861502769916, 6925.376716095576, 6929.930700693029, 6970.51206415546, 5352.9732351338, 5376.731163441812, 5399.595030372738, 5450.1007333789485, 5459.208414779835, 1641.3926847066662, 1681.1510187355307, 1694.1359906447801, 1709.0685576360913, 1717.6736420080144, 843.8312337532518, 857.6312185760058, 866.4974314897345, 875.2341701011615, 896.2158111652304, 7029.789106326818, 7107.92892071083, 7251.202367739545, 7304.379127774092, 7322.926770732331, 622.996885015572, 645.4997377032837, 705.8094314535149, 715.3418854653731, 736.576468530597, 4195.475565554306, 5175.171972484384, 5222.556082732935, 5276.457193080552, 5496.587755918322, 13795.172289662652, 17369.7394539082, 17591.73612395815, 17947.0, 17993.370232041816, 14837.62905927339, 17530.73703894417, 17577.560560986076, 18068.457946261635, 18333.63332733338, 9062.139096785926, 9251.074892510695, 9497.525123743786, 9519.286053546011, 9520.809583808285, 7128.28717128283, 7613.819857922031, 7689.538627682357, 7738.806529836799, 7794.805129871798, 12426.875731242571, 15701.293441795375, 15733.685326293638, 15910.0, 16001.919990399974, 13854.30728463593, 15426.768598470808, 16622.75065874037, 16675.33298668088, 16716.0, 10567.577296908345, 10786.083182929538, 10791.946040269748, 10808.135349172204, 10833.187510936554, 12243.69390765238, 12344.691382715504, 12350.938245308716, 12368.628941131781, 12390.62828115158, 9594.616215351307, 9690.0, 9854.802903942025, 9980.700578982642, 10000.849987250343, 9471.66849160276, 9507.572159252712, 9714.902850971399, 9868.605255789687, 10061.597536098474, 9031.006589275306, 9098.681546145854, 9124.361294709306, 9138.543072846463, 9146.954265228898, 6384.712687929147, 6614.0, 6921.8961715575315, 6961.79114626562, 7023.894641580278, 6785.185777706707, 6956.443484521327, 7124.180719217356, 7230.74692385764, 7257.963710181415], "value": 6020.724217312205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.5129600958897, 441.63123791633984, 453.36076132652965, 479.79848463645243, 480.3851070629593, 5324.600654950879, 5657.151427285909, 6500.544961852666, 6518.674066296684, 6537.8365540861505, 5251.765835028765, 6027.849303767408, 6218.0, 6277.2153480814895, 6393.890016294623, 4068.6745060395247, 4876.878078048042, 5002.12462819007, 5014.548690617844, 5063.645544811856, 4222.1766755482695, 4781.6413768967295, 5021.347224860763, 5026.899462010758, 5053.722045287508, 4944.354770197945, 4950.232713929328, 4958.454569997298, 4969.602431805447, 5015.272785446126, 4752.287156926454, 4963.627727920402, 4965.975170124144, 4991.276264941599, 4991.950080499221, 3620.0, 4302.978485107586, 4397.956020439787, 4525.800662824354, 4529.365888775574, 6281.59169653971, 6294.213223347082, 6307.716152773136, 6307.747690092391, 6335.429811316957, 5120.487951204887, 5255.369355677343, 5797.971010144965, 5864.882702345929, 6050.5478685115695, 5873.882522349571, 6630.535862489628, 6824.556403833735, 6855.828604284883, 6928.653567321614, 5239.685618862881, 5270.025045366581, 5278.621682123444, 5296.3379577552805, 5306.3632364116165, 7266.709331626728, 7266.781996540112, 7273.036280204368, 7283.999999999947, 7295.759720847469, 5161.354830646169, 5404.648697834628, 5406.729663516809, 5463.508284254436, 5486.231927530149, 4929.654924155275, 5165.509276618715, 5544.63959842609, 5559.471850174227, 5577.944220557823, 5725.484706376446, 6060.696965151725, 6065.877812604682, 6071.696415179224, 6123.540734444934, 6941.652917354112, 6944.652767361612, 6947.305269473012, 6956.686949087303, 6976.686049127801, 1793.1930631216005, 1880.7649043869517, 1891.5176629959547, 1897.4307707687915, 1899.221319259097, 1302.7134030513273, 1306.5753630070265, 1343.603636927109, 1348.1035111650838, 1353.147517064263, 5043.11680006393, 6121.785737499166, 6421.903671444932, 6432.228132624022, 6437.839054023593, 5957.880842383128, 6835.760748373783, 6881.277465866112, 7065.717371305193, 7115.5730656160795, 6209.875802483925, 6389.169407976933, 6389.712462939179, 6412.583182093243, 6435.613863168225, 3666.211764470652, 3688.5573731152435, 3706.0364305280823, 3706.240220754739, 3724.925501489955, 6049.939500604937, 6676.632785196861, 7260.636968151677, 7279.526830755984, 7325.4139668826365, 7244.13070431541, 7275.798648594601, 7280.635968201569, 7284.635768211569, 7335.48651594394, 5701.543876489871, 5704.771809127588, 5977.894089593439, 7255.891161632685, 7290.015847860471, 7064.788056358318, 7083.5041547091205, 7092.929070709433, 7104.680289387094, 7106.0, 6920.273371296143, 6961.642479716406, 6975.825604359931, 6982.79051628452, 6986.9650651746415, 5313.495217954288, 5818.799660186795, 6740.056392105207, 6926.203486599105, 6942.548734332252, 3820.656140947328, 3846.3461211593535, 4842.83050093245, 4871.562888947839, 4876.512348765095, 7047.859042819217, 7058.200158959433, 7090.326418990187, 7091.751788687371, 7115.0, 6769.864602708017, 6788.26899140716, 6797.592144471348, 6799.456043516408, 6800.489963252677, 6218.844528886814, 6761.594304341558, 6783.18601767791, 6786.0, 6797.320267973361, 6165.938340616536, 6676.899846502403, 6685.39831415175, 6712.664366781739, 6732.596044237362, 6363.0, 6741.2921643226755, 6747.696353664195, 6758.324167583202, 6994.545354551938, 5605.859853503695, 6339.366063393623, 6567.638779867153, 6623.205215374186, 6634.966825166036], "value": 5154.592923840339, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.4823092643393, 849.4648371525938, 860.7589874835046, 884.5356188001299, 884.8274586455642, 16688.249028793703, 16914.74627880581, 17109.0, 17145.485635430956, 17178.82821171788, 11425.542978280859, 12543.18469299495, 13189.538366157187, 15538.300776465057, 16497.91751041243, 960.4813400763579, 972.610955617755, 983.5918093990986, 985.522021819417, 988.2538683294102, 969.6460791810973, 1000.1748557440104, 1000.2248257600345, 1003.618624922532, 1003.7653685966266, 1024.0629823711295, 1047.7537778622018, 1051.47951763877, 1051.8159322118636, 1052.8526006359116, 939.685205456173, 971.03382134776, 1051.2431049644206, 1053.4574694032578, 1056.7675111475462, 768.3507436216388, 769.3844924060729, 771.6874665760377, 771.9459637825355, 773.5899973014311, 17181.226844792014, 17293.221805018806, 17380.392941058817, 17442.47672569825, 17477.825221747746, 14666.0, 14886.925565372212, 17292.394766183123, 17532.474025779247, 17547.0, 14183.149011059266, 14868.70262594742, 14874.553763387115, 14909.105453672706, 14922.70154596902, 3580.480830279621, 3606.675399214057, 3608.0619039049775, 3609.530761001079, 3613.981930090359, 104025.0, 118101.0, 136610.0, 148590.0, 149990.50009499985, 11608.24546404498, 13492.5277615283, 13582.86417135816, 13644.863551364559, 13781.86218137826, 26173.0, 26430.6035409469, 26565.734342656327, 26596.20211393661, 26800.865995670287, 7771.028621422322, 7833.255840695125, 7842.411819113589, 7848.13670496245, 7857.9214207857185, 3615.493830863681, 3738.532683414573, 3775.9433608495347, 3787.0, 3791.1280405506336, 4613.65397595175, 4651.813927442932, 4686.6485013624115, 4697.741624210701, 4708.293755936637, 876.5617191404298, 918.912703293186, 921.4010892919656, 921.6451666108596, 929.6095639831287, 572.4533070917264, 610.9419605137505, 653.6045692356151, 665.0323778901686, 691.4952084977995, 8743.431676940978, 11709.126539753599, 12089.274643521418, 12188.939055304667, 12369.958956772212, 637.2607774981, 652.7356420649644, 658.2627457247818, 658.555475054346, 673.9966300168468, 5417.512423881791, 5552.694601796859, 5625.0, 5673.0, 5900.262467191601, 130579.0, 133203.0, 136230.31884840512, 137530.0, 140429.0, 124108.37945810214, 128566.0, 129253.0, 129517.35241323922, 132301.33849330692, 110663.0, 113407.29889051671, 113955.0, 116895.0, 116927.41536292263, 9596.712098637052, 11042.227044106836, 11092.944535277595, 11284.830727539258, 11393.803650616566, 44286.55713442953, 45536.77231613821, 46355.0, 46413.0, 47916.28125578189, 60657.69671151616, 61132.388676114446, 63693.0, 64033.35966640274, 65029.349706504225, 14552.126872387691, 14711.411543538567, 14878.553643390716, 15102.848971510584, 15125.941184117446, 16061.0, 16245.944013639075, 16364.2636081379, 16477.258523366716, 16672.83327166713, 14541.636459088608, 14797.112173269637, 14845.777313340093, 14883.032602880778, 15209.543713688165, 14552.345144468312, 14758.55724328272, 14961.102333860003, 15034.17312047848, 15277.618059548156, 14182.503612373517, 14353.138811671333, 14672.633184170481, 14839.629009274855, 14858.628534286729, 9943.900126422433, 10564.207684423853, 10810.027097561257, 10859.782804343711, 11023.007929286403, 9285.814283714422, 10199.745006374604, 10971.067459266053, 11387.943060284646, 11439.942800285946], "value": 8428.695045575178, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 115.0, 129.0, 159.0, 176.0, 145.0, 117.0, 139.0, 151.0], "value": 134.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 171.0, 163.0, 135.0, 164.0, 174.0, 154.0, 161.0, 110.0], "value": 149.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 204.0, 216.0, 291.0, 229.0, 221.0, 248.0, 282.0, 201.0, 204.0], "value": 229.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 100.0, 102.0, 131.0, 55.0, 60.0, 95.0, 61.0, 93.0, 50.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 114.0, 100.0, 93.0, 93.0, 111.0, 73.0, 76.0, 109.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 136.0, 123.0, 89.0, 88.0, 89.0, 90.0, 89.0, 89.0], "value": 97.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 157.0, 116.0, 96.0, 97.0, 95.0, 96.0, 98.0, 110.0], "value": 105.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 91.0, 91.0, 94.0, 93.0, 91.0, 92.0, 91.0, 92.0, 91.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 50.0, 53.0, 55.0, 62.0, 78.0, 55.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 65.0, 82.0, 80.0, 63.0, 55.0, 71.0, 53.0, 51.0], "value": 63.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 78.0, 82.0, 81.0, 79.0, 82.0, 82.0, 75.0, 94.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 157.0, 138.0, 139.0, 138.0, 139.0, 146.0, 138.0, 161.0, 159.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 182.0, 181.0, 183.0, 165.0, 211.0, 237.0, 199.0, 182.0, 176.0], "value": 189.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 69.0, 66.0, 69.0, 64.0, 68.0, 63.0, 75.0], "value": 67.9, "unit": "score"}]}]} 08:48:58 INFO - Return code: 0 08:48:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:48:58 INFO - # TBPL SUCCESS # 08:48:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:48:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:48:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:48:58 INFO - 2017-06-12 08:48:58.172 screenresolution[2637:26118] starting screenresolution argv=screenresolution get 08:48:58 INFO - 2017-06-12 08:48:58.177 screenresolution[2637:26118] Display 0: 1600x1200x32@60 08:48:58 INFO - 2017-06-12 08:48:58.183 screenresolution[2638:26121] starting screenresolution argv=screenresolution list 08:48:58 INFO - Available Modes on Display 0 08:48:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:48:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:48:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:48:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:48:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:48:58 INFO - Intel Iris: 08:48:58 INFO - Chipset Model: Intel Iris 08:48:58 INFO - Type: GPU 08:48:58 INFO - Bus: Built-In 08:48:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:48:58 INFO - Vendor: Intel (0x8086) 08:48:58 INFO - Device ID: 0x0a2e 08:48:58 INFO - Revision ID: 0x0009 08:48:58 INFO - Displays: 08:48:58 INFO - Display: 08:48:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:48:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:48:58 INFO - Display Serial Number: 1600x1200 60 08:48:58 INFO - Main Display: Yes 08:48:58 INFO - Mirror: Off 08:48:58 INFO - Online: Yes 08:48:58 INFO - Rotation: Supported 08:48:58 INFO - Return code: 0 08:48:58 INFO - Running post-action listener: _resource_record_post_action 08:48:58 INFO - [mozharness: 2017-06-12 15:48:58.428976Z] Finished run-tests step (success) 08:48:58 INFO - Running post-run listener: _resource_record_post_run 08:48:58 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 32768; Write bytes: 571430912; Read time: 1; Write time: 1806 08:48:58 INFO - TinderboxPrint: CPU usage
53.2% 08:48:58 INFO - TinderboxPrint: I/O read bytes / time
32,768 / 1 08:48:58 INFO - TinderboxPrint: I/O write bytes / time
571,430,912 / 1,806 08:48:58 INFO - TinderboxPrint: CPU idle
2,353.1 (46.8%) 08:48:58 INFO - TinderboxPrint: CPU system
1,019.8 (20.3%) 08:48:58 INFO - TinderboxPrint: CPU user
1,656.9 (32.9%) 08:48:58 INFO - TinderboxPrint: Swap in / out
251,772,928 / 0 08:48:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 251892224; Write bytes: 247318016; Read time: 18075; Write time: 541 08:48:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:48:58 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 32768; Write bytes: 313840128; Read time: 1; Write time: 1223 08:48:58 INFO - Running post-run listener: _upload_blobber_files 08:48:58 INFO - Blob upload gear active. 08:48:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:48:58 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 08:48:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:48:59 INFO - (blobuploader) - INFO - Open directory for files ... 08:48:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:48:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:49:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:00 INFO - (blobuploader) - INFO - Done attempting. 08:49:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:49:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:49:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:01 INFO - (blobuploader) - INFO - Done attempting. 08:49:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:49:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:49:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:02 INFO - (blobuploader) - INFO - Done attempting. 08:49:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:49:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:49:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:05 INFO - (blobuploader) - INFO - Done attempting. 08:49:05 INFO - (blobuploader) - INFO - Iteration through files over. 08:49:05 INFO - Return code: 0 08:49:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:49:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:49:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a5d4cd1e7dda2bb3af80f68dfd0a6ec130a53799bf14c9c2e3abb44fe6256ec64086a0caffc05cb016186d5c3a45f3ba7c18e31bb2e1fb713f711f46cbe0881", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4554554b9380fe6da299de8ad341eaf50da733da844b277f600ce9994362ec0a38e7eb8f6b642b70ba2ffbc305303589e534d2d6f9ed7745d5d4f5aef4ed6b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a897a868d5218d4e610f51b245cef4e379471411236e29ed106bb23f6789c336bc03b8690f80c3cf9738e471fb4a9554fca6c20d501cfd27f80bd41d1c06c59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7856b10cd03e0be57deca52e944d0be9eeb62ac1bc96e350f90dcdacc43b92c36a7b02e175eeba10fe2575d7b1cd9fdd24a9239223e4422bd0ec76a42b35f9fc"} 08:49:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:49:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:49:05 INFO - Contents: 08:49:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a5d4cd1e7dda2bb3af80f68dfd0a6ec130a53799bf14c9c2e3abb44fe6256ec64086a0caffc05cb016186d5c3a45f3ba7c18e31bb2e1fb713f711f46cbe0881", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4554554b9380fe6da299de8ad341eaf50da733da844b277f600ce9994362ec0a38e7eb8f6b642b70ba2ffbc305303589e534d2d6f9ed7745d5d4f5aef4ed6b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a897a868d5218d4e610f51b245cef4e379471411236e29ed106bb23f6789c336bc03b8690f80c3cf9738e471fb4a9554fca6c20d501cfd27f80bd41d1c06c59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7856b10cd03e0be57deca52e944d0be9eeb62ac1bc96e350f90dcdacc43b92c36a7b02e175eeba10fe2575d7b1cd9fdd24a9239223e4422bd0ec76a42b35f9fc"} 08:49:05 INFO - Running post-run listener: copy_logs_to_upload_dir 08:49:05 INFO - Copying logs to upload dir... 08:49:05 INFO - mkdir: /builds/slave/test/build/upload/logs 08:49:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1342.636114 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-06-12 08:49:05.154691) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 08:49:05.157864) ========= 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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a5d4cd1e7dda2bb3af80f68dfd0a6ec130a53799bf14c9c2e3abb44fe6256ec64086a0caffc05cb016186d5c3a45f3ba7c18e31bb2e1fb713f711f46cbe0881", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4554554b9380fe6da299de8ad341eaf50da733da844b277f600ce9994362ec0a38e7eb8f6b642b70ba2ffbc305303589e534d2d6f9ed7745d5d4f5aef4ed6b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a897a868d5218d4e610f51b245cef4e379471411236e29ed106bb23f6789c336bc03b8690f80c3cf9738e471fb4a9554fca6c20d501cfd27f80bd41d1c06c59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7856b10cd03e0be57deca52e944d0be9eeb62ac1bc96e350f90dcdacc43b92c36a7b02e175eeba10fe2575d7b1cd9fdd24a9239223e4422bd0ec76a42b35f9fc"} build_url:https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011519 build_url: 'https://queue.taskcluster.net/v1/task/GuUu_pQPTUKn3RWXhZe67g/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a5d4cd1e7dda2bb3af80f68dfd0a6ec130a53799bf14c9c2e3abb44fe6256ec64086a0caffc05cb016186d5c3a45f3ba7c18e31bb2e1fb713f711f46cbe0881", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4554554b9380fe6da299de8ad341eaf50da733da844b277f600ce9994362ec0a38e7eb8f6b642b70ba2ffbc305303589e534d2d6f9ed7745d5d4f5aef4ed6b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a897a868d5218d4e610f51b245cef4e379471411236e29ed106bb23f6789c336bc03b8690f80c3cf9738e471fb4a9554fca6c20d501cfd27f80bd41d1c06c59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7856b10cd03e0be57deca52e944d0be9eeb62ac1bc96e350f90dcdacc43b92c36a7b02e175eeba10fe2575d7b1cd9fdd24a9239223e4422bd0ec76a42b35f9fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 08:49:05.191829) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 08:49:05.192149) ========= 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.ZdfT6v7N0o/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.vgfykMUkEE/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.004766 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 08:49:05.240303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 08:49:05.240627) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 08:49:05.240979) ========= ========= Total master_lag: 0.18 =========