builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0385 starttime: 1495794460.39 results: success (0) revision: bedd24174ff464c4318db7224aa47ba84c25abec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:40.389615) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:40.390061) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2017-05-26 03:27:40.390335) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2017-05-26 03:27:44.266218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:44.266555) ========= 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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-26 03:27:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.6M=0.001s 2017-05-26 03:27:44 (19.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091597 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:44.481977) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:44.482303) ========= 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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.027099 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:44.582575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 03:27:44.582930) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bedd24174ff464c4318db7224aa47ba84c25abec --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 bedd24174ff464c4318db7224aa47ba84c25abec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pPa8ufzzo9/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.T8SfYBx2TU/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-26 03:27:44,679 truncating revision to first 12 chars 2017-05-26 03:27:44,679 Setting DEBUG logging. 2017-05-26 03:27:44,679 attempt 1/10 2017-05-26 03:27:44,679 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bedd24174ff4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 03:27:45,456 unpacking tar archive at: mozilla-central-bedd24174ff4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.066176 ========= master_lag: 0.64 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 03:27:46.290423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:46.290758) ========= script_repo_revision: bedd24174ff464c4318db7224aa47ba84c25abec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:46.291158) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:46.291431) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:46.577663) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-26 03:27:46.578062) ========= /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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:27:46 INFO - MultiFileLogger online at 20170526 03:27:46 in /builds/slave/test 03:27:46 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 03:27:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:27:46 INFO - {'append_to_log': False, 03:27:46 INFO - 'base_work_dir': '/builds/slave/test', 03:27:46 INFO - 'blob_upload_branch': 'Firefox', 03:27:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:27:46 INFO - 'branch': 'Firefox', 03:27:46 INFO - 'buildbot_json_path': 'buildprops.json', 03:27:46 INFO - 'config_files': ('talos/mac_config.py',), 03:27:46 INFO - 'default_actions': ('clobber', 03:27:46 INFO - 'read-buildbot-config', 03:27:46 INFO - 'download-and-extract', 03:27:46 INFO - 'populate-webroot', 03:27:46 INFO - 'create-virtualenv', 03:27:46 INFO - 'install', 03:27:46 INFO - 'run-tests'), 03:27:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:27:46 INFO - 'download_minidump_stackwalk': True, 03:27:46 INFO - 'download_symbols': 'ondemand', 03:27:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:27:46 INFO - 'tooltool.py': '/tools/tooltool.py', 03:27:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:27:46 INFO - '/tools/misc-python/virtualenv.py')}, 03:27:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:27:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:27:46 INFO - 'gecko_profile': False, 03:27:46 INFO - 'gecko_profile_interval': 0, 03:27:46 INFO - 'installer_path': 'installer.exe', 03:27:46 INFO - 'log_level': 'info', 03:27:46 INFO - 'log_name': 'talos', 03:27:46 INFO - 'log_to_console': True, 03:27:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:27:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:27:46 INFO - 'opt_config_files': (), 03:27:46 INFO - 'pip_index': False, 03:27:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:27:46 INFO - 'cmd': ('bash', 03:27:46 INFO - '-c', 03:27:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:27:46 INFO - 'enabled': True, 03:27:46 INFO - 'halt_on_failure': False, 03:27:46 INFO - 'name': 'check_screen_resolution'},), 03:27:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:27:46 INFO - 'cmd': ('bash', 03:27:46 INFO - '-c', 03:27:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:27:46 INFO - 'enabled': True, 03:27:46 INFO - 'halt_on_failure': False, 03:27:46 INFO - 'name': 'check_screen_resolution'},), 03:27:46 INFO - 'run_cmd_checks_enabled': True, 03:27:46 INFO - 'suite': 'dromaeojs-e10s', 03:27:46 INFO - 'system_bits': '32', 03:27:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:27:46 INFO - 'title': 't-yosemite-r7-0385', 03:27:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:27:46 INFO - 'use_talos_json': True, 03:27:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:27:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:27:46 INFO - 'work_dir': 'build'} 03:27:46 INFO - [mozharness: 2017-05-26 10:27:46.784740Z] Running clobber step. 03:27:46 INFO - Running pre-action listener: _resource_record_pre_action 03:27:46 INFO - Running main action method: clobber 03:27:46 INFO - rmtree: /builds/slave/test/build 03:27:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:27:49 INFO - Running post-action listener: _resource_record_post_action 03:27:49 INFO - [mozharness: 2017-05-26 10:27:49.249688Z] Finished clobber step (success) 03:27:49 INFO - [mozharness: 2017-05-26 10:27:49.249852Z] Running read-buildbot-config step. 03:27:49 INFO - Running pre-action listener: _resource_record_pre_action 03:27:49 INFO - Running main action method: read_buildbot_config 03:27:49 INFO - Using buildbot properties: 03:27:49 INFO - { 03:27:49 INFO - "project": "", 03:27:49 INFO - "product": "firefox", 03:27:49 INFO - "who": "cbook@mozilla.com", 03:27:49 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:27:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:27:49 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:27:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:27:49 INFO - "stage_platform": "macosx64", 03:27:49 INFO - "basedir": "/builds/slave/test", 03:27:49 INFO - "buildnumber": 224, 03:27:49 INFO - "platform": "yosemite_r7", 03:27:49 INFO - "parent_task_id": "cK1EQdUSQsO8-lYMagD1mg", 03:27:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:27:49 INFO - "slavebuilddir": "test", 03:27:49 INFO - "taskId": "bUimx83-RJe1Ay1N5KGUNQ", 03:27:49 INFO - "branch": "mozilla-central", 03:27:49 INFO - "script_repo_revision": "production", 03:27:49 INFO - "revision": "bedd24174ff464c4318db7224aa47ba84c25abec", 03:27:49 INFO - "slavename": "t-yosemite-r7-0385", 03:27:49 INFO - "repo_path": "mozilla-central" 03:27:49 INFO - } 03:27:49 INFO - Finding installer, test and symbols from parent task. 03:27:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bUimx83-RJe1Ay1N5KGUNQ'}, attempt #1 03:27:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bUimx83-RJe1Ay1N5KGUNQ'}, attempt #1 03:27:50 INFO - Task dependencies: UnlRTI8ZQ1-07AAL1jaggQ cK1EQdUSQsO8-lYMagD1mg 03:27:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg'}, attempt #1 03:27:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:27:50 INFO - Set test_url: https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/test_packages.json 03:27:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/target.crashreporter-symbols.zip 03:27:50 INFO - Running post-action listener: _resource_record_post_action 03:27:50 INFO - [mozharness: 2017-05-26 10:27:50.944962Z] Finished read-buildbot-config step (success) 03:27:50 INFO - [mozharness: 2017-05-26 10:27:50.945100Z] Running download-and-extract step. 03:27:50 INFO - Running pre-action listener: _resource_record_pre_action 03:27:50 INFO - Running main action method: download_and_extract 03:27:50 INFO - mkdir: /builds/slave/test/build/tests 03:27:50 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')]} 03:27:50 INFO - trying https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:27:50 INFO - Downloading https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:27:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 03:27:51 INFO - Downloaded 1325 bytes. 03:27:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:27:51 INFO - Using the following test package requirements: 03:27:51 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:27:51 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:27:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:27:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:27:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'jsshell-mac.zip'], 03:27:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:27:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:27:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:27:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:27:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:27:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:27:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:27:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:27:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:27:51 INFO - Fetch https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:27:53 INFO - Content-Length response header: 57629914 03:27:53 INFO - Bytes received: 57629914 03:28:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:28:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:28:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:28:11 INFO - Fetch https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:28:13 INFO - Content-Length response header: 57629914 03:28:13 INFO - Bytes received: 57629914 03:28:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:28:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:28:30 INFO - Fetch https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:28:31 INFO - Content-Length response header: 14411888 03:28:31 INFO - Bytes received: 14411888 03:28:31 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')]} 03:28:31 INFO - trying https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:31 INFO - Downloading https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:28:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 03:28:33 INFO - Downloaded 67214268 bytes. 03:28:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:33 INFO - mkdir: /builds/slave/test/properties 03:28:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:28:33 INFO - Writing to file /builds/slave/test/properties/build_url 03:28:33 INFO - Contents: 03:28:33 INFO - build_url:https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:33 INFO - Running post-action listener: _resource_record_post_action 03:28:33 INFO - Running post-action listener: set_extra_try_arguments 03:28:33 INFO - [mozharness: 2017-05-26 10:28:33.937870Z] Finished download-and-extract step (success) 03:28:33 INFO - [mozharness: 2017-05-26 10:28:33.937991Z] Running populate-webroot step. 03:28:33 INFO - Running pre-action listener: _resource_record_pre_action 03:28:33 INFO - Running main action method: populate_webroot 03:28:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:28:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:28:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:28:33 INFO - '--tppagecycles', 03:28:33 INFO - '7'], 03:28:33 INFO - 'tests': ['tsvgm']}}, 03:28:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:28:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:28:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:28:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:28:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:28:33 INFO - 'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:28:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:28:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:28:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:28:33 INFO - 'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['damp', 'tps']}, 03:28:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:28:33 INFO - 'tests': ['damp', 'tps']}, 03:28:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['dromaeo_dom']}, 03:28:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:28:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:28:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:28:33 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:28:33 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:28:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['a11yr', 03:28:33 INFO - 'ts_paint', 03:28:33 INFO - 'tpaint', 03:28:33 INFO - 'sessionrestore', 03:28:33 INFO - 'sessionrestore_no_auto_restore', 03:28:33 INFO - 'tabpaint']}, 03:28:33 INFO - 'other-e10s': {'tests': ['a11yr', 03:28:33 INFO - 'ts_paint', 03:28:33 INFO - 'tpaint', 03:28:33 INFO - 'sessionrestore', 03:28:33 INFO - 'sessionrestore_no_auto_restore', 03:28:33 INFO - 'tabpaint']}, 03:28:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:28:33 INFO - 'ts_paint', 03:28:33 INFO - 'tpaint', 03:28:33 INFO - 'sessionrestore', 03:28:33 INFO - 'sessionrestore_no_auto_restore', 03:28:33 INFO - 'tabpaint']}, 03:28:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:28:33 INFO - 'ts_paint', 03:28:33 INFO - 'tpaint', 03:28:33 INFO - 'sessionrestore', 03:28:33 INFO - 'sessionrestore_no_auto_restore', 03:28:33 INFO - 'tabpaint']}, 03:28:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['a11yr', 03:28:33 INFO - 'ts_paint', 03:28:33 INFO - 'tpaint', 03:28:33 INFO - 'sessionrestore', 03:28:33 INFO - 'sessionrestore_no_auto_restore', 03:28:33 INFO - 'tabpaint']}, 03:28:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['a11yr', 03:28:33 INFO - 'ts_paint', 03:28:33 INFO - 'tpaint', 03:28:33 INFO - 'sessionrestore', 03:28:33 INFO - 'sessionrestore_no_auto_restore', 03:28:33 INFO - 'tabpaint']}, 03:28:33 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:28:33 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:28:33 INFO - 'bloom_basic_ref']}, 03:28:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['tsvgx', 03:28:33 INFO - 'tsvgr_opacity', 03:28:33 INFO - 'tart', 03:28:33 INFO - 'tscrollx', 03:28:33 INFO - 'cart', 03:28:33 INFO - 'tsvg_static']}, 03:28:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:28:33 INFO - 'tsvgr_opacity', 03:28:33 INFO - 'tart', 03:28:33 INFO - 'tscrollx', 03:28:33 INFO - 'cart', 03:28:33 INFO - 'tsvg_static']}, 03:28:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:28:33 INFO - 'talos_options': ['--disable-e10s'], 03:28:33 INFO - 'tests': ['tp5o']}, 03:28:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:28:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:28:33 INFO - 'talos_options': ['--disable-e10s', 03:28:33 INFO - '--xperf_path', 03:28:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:28:33 INFO - 'tests': ['tp5n']}, 03:28:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:28:33 INFO - 'talos_options': ['--xperf_path', 03:28:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:28:33 INFO - 'tests': ['tp5n']}}, 03:28:33 INFO - 'talos.zip': {'path': '', 03:28:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:28:33 INFO - Running post-action listener: _resource_record_post_action 03:28:33 INFO - [mozharness: 2017-05-26 10:28:33.959252Z] Finished populate-webroot step (success) 03:28:33 INFO - [mozharness: 2017-05-26 10:28:33.959405Z] Running create-virtualenv step. 03:28:33 INFO - Running pre-action listener: _resource_record_pre_action 03:28:33 INFO - Running main action method: create_virtualenv 03:28:33 INFO - Creating virtualenv /builds/slave/test/build/venv 03:28:33 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 03:28:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:28:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:28:34 INFO - Using real prefix '/tools/python27' 03:28:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:28:34 INFO - Installing distribute.............................................................................................................................................................................................done. 03:28:37 INFO - Installing pip.................done. 03:28:37 INFO - Return code: 0 03:28:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:28:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:28:37 INFO - Reading from file tmpfile_stdout 03:28:37 INFO - Output received: 03:28:37 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) 03:28:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:28:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:38 INFO - 'HOME': '/Users/cltbld', 03:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:38 INFO - 'LOGNAME': 'cltbld', 03:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:38 INFO - 'MOZ_NO_REMOTE': '1', 03:28:38 INFO - 'NO_EM_RESTART': '1', 03:28:38 INFO - 'PAGER': '/bin/cat', 03:28:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:38 INFO - 'PWD': '/builds/slave/test', 03:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:38 INFO - 'SHELL': '/bin/bash', 03:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:38 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:38 INFO - 'USER': 'cltbld', 03:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:38 INFO - 'XPC_FLAGS': '0x0', 03:28:38 INFO - 'XPC_SERVICE_NAME': '0', 03:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:38 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 03:28:38 INFO - Cleaning up... 03:28:38 INFO - Return code: 0 03:28:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:28:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:39 INFO - 'HOME': '/Users/cltbld', 03:28:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:39 INFO - 'LOGNAME': 'cltbld', 03:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:39 INFO - 'MOZ_NO_REMOTE': '1', 03:28:39 INFO - 'NO_EM_RESTART': '1', 03:28:39 INFO - 'PAGER': '/bin/cat', 03:28:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:39 INFO - 'PWD': '/builds/slave/test', 03:28:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:39 INFO - 'SHELL': '/bin/bash', 03:28:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:39 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:39 INFO - 'USER': 'cltbld', 03:28:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:39 INFO - 'XPC_FLAGS': '0x0', 03:28:39 INFO - 'XPC_SERVICE_NAME': '0', 03:28:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:39 INFO - Downloading/unpacking psutil>=3.1.1 03:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:28:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:41 INFO - Installing collected packages: psutil 03:28:41 INFO - Running setup.py install for psutil 03:28:41 INFO - building 'psutil._psutil_osx' extension 03:28:41 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 03:28:42 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 03:28:42 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 03:28:42 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 03:28:42 INFO - building 'psutil._psutil_posix' extension 03:28:42 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 03:28:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:28:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:28:42 INFO - ^ 03:28:42 INFO - 1 warning generated. 03:28:42 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 03:28:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:42 INFO - Successfully installed psutil 03:28:42 INFO - Cleaning up... 03:28:43 INFO - Return code: 0 03:28:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:28:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:43 INFO - 'HOME': '/Users/cltbld', 03:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:43 INFO - 'LOGNAME': 'cltbld', 03:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:43 INFO - 'MOZ_NO_REMOTE': '1', 03:28:43 INFO - 'NO_EM_RESTART': '1', 03:28:43 INFO - 'PAGER': '/bin/cat', 03:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:43 INFO - 'PWD': '/builds/slave/test', 03:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:43 INFO - 'SHELL': '/bin/bash', 03:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:43 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:43 INFO - 'USER': 'cltbld', 03:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:43 INFO - 'XPC_FLAGS': '0x0', 03:28:43 INFO - 'XPC_SERVICE_NAME': '0', 03:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:28: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 03:28: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 03:28: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 03:28: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 03:28:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:28:44 INFO - Installing collected packages: mozsystemmonitor 03:28:44 INFO - Running setup.py install for mozsystemmonitor 03:28:45 INFO - Successfully installed mozsystemmonitor 03:28:45 INFO - Cleaning up... 03:28:45 INFO - Return code: 0 03:28:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28: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')]} 03:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28: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', '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 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:28: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 jsonschema==2.5.1 03:28:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:45 INFO - 'HOME': '/Users/cltbld', 03:28:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:45 INFO - 'LOGNAME': 'cltbld', 03:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:45 INFO - 'MOZ_NO_REMOTE': '1', 03:28:45 INFO - 'NO_EM_RESTART': '1', 03:28:45 INFO - 'PAGER': '/bin/cat', 03:28:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:45 INFO - 'PWD': '/builds/slave/test', 03:28:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:45 INFO - 'SHELL': '/bin/bash', 03:28:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:45 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:45 INFO - 'USER': 'cltbld', 03:28:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:45 INFO - 'XPC_FLAGS': '0x0', 03:28:45 INFO - 'XPC_SERVICE_NAME': '0', 03:28:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:45 INFO - Downloading/unpacking jsonschema==2.5.1 03:28: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 03:28: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 03:28: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 03:28: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 03:28:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:28:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:46 INFO - Downloading functools32-3.2.3-2.tar.gz 03:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:28:46 INFO - warning: no files found matching '*.txt' 03:28:46 INFO - no previously-included directories found matching 'build' 03:28:46 INFO - no previously-included directories found matching 'dist' 03:28:46 INFO - no previously-included directories found matching '.git*' 03:28:46 INFO - Installing collected packages: jsonschema, functools32 03:28:47 INFO - Running setup.py install for functools32 03:28:47 INFO - warning: no files found matching '*.txt' 03:28:47 INFO - no previously-included directories found matching 'build' 03:28:47 INFO - no previously-included directories found matching 'dist' 03:28:47 INFO - no previously-included directories found matching '.git*' 03:28:47 INFO - Successfully installed jsonschema functools32 03:28:47 INFO - Cleaning up... 03:28:47 INFO - Return code: 0 03:28:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:47 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 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28:47 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 03:28:47 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 03:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:47 INFO - 'HOME': '/Users/cltbld', 03:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:47 INFO - 'LOGNAME': 'cltbld', 03:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:47 INFO - 'MOZ_NO_REMOTE': '1', 03:28:47 INFO - 'NO_EM_RESTART': '1', 03:28:47 INFO - 'PAGER': '/bin/cat', 03:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:47 INFO - 'PWD': '/builds/slave/test', 03:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:47 INFO - 'SHELL': '/bin/bash', 03:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:47 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:47 INFO - 'USER': 'cltbld', 03:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:47 INFO - 'XPC_FLAGS': '0x0', 03:28:47 INFO - 'XPC_SERVICE_NAME': '0', 03:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:28:47 INFO - Cleaning up... 03:28:47 INFO - Return code: 0 03:28:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:47 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 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28:47 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 03:28:47 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 03:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:47 INFO - 'HOME': '/Users/cltbld', 03:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:47 INFO - 'LOGNAME': 'cltbld', 03:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:47 INFO - 'MOZ_NO_REMOTE': '1', 03:28:47 INFO - 'NO_EM_RESTART': '1', 03:28:47 INFO - 'PAGER': '/bin/cat', 03:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:47 INFO - 'PWD': '/builds/slave/test', 03:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:47 INFO - 'SHELL': '/bin/bash', 03:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:47 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:47 INFO - 'USER': 'cltbld', 03:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:47 INFO - 'XPC_FLAGS': '0x0', 03:28:47 INFO - 'XPC_SERVICE_NAME': '0', 03:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:47 INFO - Downloading/unpacking blobuploader==1.2.4 03:28: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 03:28: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 03:28: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 03:28: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 03:28:49 INFO - Downloading blobuploader-1.2.4.tar.gz 03:28:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:28:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:28:49 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 03:28:49 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 03:28:49 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 03:28:49 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 03:28:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:28:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:28:49 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 03:28:49 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 03:28:49 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 03:28:49 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 03:28:50 INFO - Downloading docopt-0.6.1.tar.gz 03:28:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:28:50 INFO - Installing collected packages: blobuploader, requests, docopt 03:28:50 INFO - Running setup.py install for blobuploader 03:28:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:28:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:28:50 INFO - Running setup.py install for requests 03:28:50 INFO - Running setup.py install for docopt 03:28:50 INFO - Successfully installed blobuploader requests docopt 03:28:50 INFO - Cleaning up... 03:28:50 INFO - Return code: 0 03:28:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:50 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')]} 03:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:50 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')]} 03:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:50 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 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28:50 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 03:28:50 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 03:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:50 INFO - 'HOME': '/Users/cltbld', 03:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:50 INFO - 'LOGNAME': 'cltbld', 03:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:50 INFO - 'MOZ_NO_REMOTE': '1', 03:28:50 INFO - 'NO_EM_RESTART': '1', 03:28:50 INFO - 'PAGER': '/bin/cat', 03:28:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:50 INFO - 'PWD': '/builds/slave/test', 03:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:50 INFO - 'SHELL': '/bin/bash', 03:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:50 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:50 INFO - 'USER': 'cltbld', 03:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:50 INFO - 'XPC_FLAGS': '0x0', 03:28:50 INFO - 'XPC_SERVICE_NAME': '0', 03:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-q4H_sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-borFzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-mxA07c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-JciZNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-DuVR26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-yblfXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-3m67t4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-O38oQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-XVZxI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-puNWNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-LXbuJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2bJw4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-bb3xrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ebplCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-zf_K5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-EEemsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-AVl8cR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:28:53 INFO - Running setup.py install for manifestparser 03:28:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:28:53 INFO - Running setup.py install for mozcrash 03:28:53 INFO - Running setup.py install for mozdebug 03:28:53 INFO - Running setup.py install for mozdevice 03:28:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:28:53 INFO - Running setup.py install for mozfile 03:28:53 INFO - Running setup.py install for mozhttpd 03:28:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:28:53 INFO - Running setup.py install for mozinfo 03:28:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Running setup.py install for mozInstall 03:28:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Running setup.py install for mozleak 03:28:54 INFO - Running setup.py install for mozlog 03:28:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Running setup.py install for moznetwork 03:28:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Running setup.py install for mozprocess 03:28:54 INFO - Running setup.py install for mozprofile 03:28:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:28:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:28:55 INFO - Running setup.py install for mozrunner 03:28:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:28:55 INFO - Running setup.py install for mozscreenshot 03:28:55 INFO - Running setup.py install for moztest 03:28:55 INFO - Running setup.py install for mozversion 03:28:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:28:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:28:55 INFO - Cleaning up... 03:28:55 INFO - Return code: 0 03:28:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:55 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')]} 03:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:55 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')]} 03:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:55 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 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28:55 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 03:28:55 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 03:28:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:55 INFO - 'HOME': '/Users/cltbld', 03:28:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:55 INFO - 'LOGNAME': 'cltbld', 03:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:55 INFO - 'MOZ_NO_REMOTE': '1', 03:28:55 INFO - 'NO_EM_RESTART': '1', 03:28:55 INFO - 'PAGER': '/bin/cat', 03:28:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:55 INFO - 'PWD': '/builds/slave/test', 03:28:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:55 INFO - 'SHELL': '/bin/bash', 03:28:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:55 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:55 INFO - 'USER': 'cltbld', 03:28:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:55 INFO - 'XPC_FLAGS': '0x0', 03:28:55 INFO - 'XPC_SERVICE_NAME': '0', 03:28:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-W1dqQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:56 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)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-euY7AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:56 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)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-HoTa9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:56 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)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-FAp16B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:56 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)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-b1qpYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:56 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)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-p_eRfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:56 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)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-QMznCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-buhhBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-9ENaa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:56 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)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-lkoYLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:56 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)) 03:28:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-FiQOJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:57 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)) 03:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:57 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-3aPzrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:57 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)) 03:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:57 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-vI0RBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:57 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)) 03:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:57 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-hD8Zgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:57 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)) 03:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:57 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-8i1Q8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:57 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)) 03:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:57 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-aCoIZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:57 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)) 03:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:57 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-qG_hME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:57 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)) 03:28:57 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)) 03:28:57 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)) 03:28:57 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)) 03:28:57 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)) 03:28:57 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)) 03:28:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:28: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 03:28: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 03:28: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 03:28: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 03:28:59 INFO - Downloading blessings-1.6.tar.gz 03:28:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:28:59 INFO - Installing collected packages: blessings 03:28:59 INFO - Running setup.py install for blessings 03:28:59 INFO - Successfully installed blessings 03:28:59 INFO - Cleaning up... 03:28:59 INFO - Return code: 0 03:28:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:28:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:59 INFO - Reading from file tmpfile_stdout 03:28:59 INFO - Current package versions: 03:28:59 INFO - blessings == 1.6 03:28:59 INFO - blobuploader == 1.2.4 03:28:59 INFO - docopt == 0.6.1 03:28:59 INFO - functools32 == 3.2.3-2 03:28:59 INFO - jsonschema == 2.5.1 03:28:59 INFO - manifestparser == 1.1 03:28:59 INFO - mozInstall == 1.12 03:28:59 INFO - mozcrash == 1.0 03:28:59 INFO - mozdebug == 0.1 03:28:59 INFO - mozdevice == 0.51 03:28:59 INFO - mozfile == 1.2 03:28:59 INFO - mozhttpd == 0.7 03:28:59 INFO - mozinfo == 0.9 03:28:59 INFO - mozleak == 0.1 03:28:59 INFO - mozlog == 3.4 03:28:59 INFO - moznetwork == 0.27 03:28:59 INFO - mozprocess == 0.25 03:28:59 INFO - mozprofile == 0.28 03:28:59 INFO - mozrunner == 6.13 03:28:59 INFO - mozscreenshot == 0.1 03:28:59 INFO - mozsystemmonitor == 0.3 03:28:59 INFO - moztest == 0.8 03:28:59 INFO - mozversion == 1.4 03:28:59 INFO - psutil == 3.1.1 03:28:59 INFO - requests == 1.2.3 03:28:59 INFO - wsgiref == 0.1.2 03:28:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28: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')]} 03:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:59 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 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:28:59 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 03:28:59 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 03:28:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:28:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:59 INFO - 'HOME': '/Users/cltbld', 03:28:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:59 INFO - 'LOGNAME': 'cltbld', 03:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:59 INFO - 'MOZ_NO_REMOTE': '1', 03:28:59 INFO - 'NO_EM_RESTART': '1', 03:28:59 INFO - 'PAGER': '/bin/cat', 03:28:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:59 INFO - 'PWD': '/builds/slave/test', 03:28:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:59 INFO - 'SHELL': '/bin/bash', 03:28:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:28:59 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:28:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:59 INFO - 'USER': 'cltbld', 03:28:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:59 INFO - 'XPC_FLAGS': '0x0', 03:28:59 INFO - 'XPC_SERVICE_NAME': '0', 03:28:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:59 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)) 03:28:59 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)) 03:28:59 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)) 03:28:59 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)) 03:28:59 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)) 03:28:59 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)) 03:28:59 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)) 03:28:59 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)) 03:28:59 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)) 03:28:59 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:28:59 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 03:28:59 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 03:28:59 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 03:28:59 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 03:29:01 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:29:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:29:01 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)) 03:29:01 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)) 03:29:01 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)) 03:29:01 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)) 03:29:01 INFO - Installing collected packages: marionette-driver 03:29:01 INFO - Running setup.py install for marionette-driver 03:29:01 INFO - Successfully installed marionette-driver 03:29:01 INFO - Cleaning up... 03:29:01 INFO - Return code: 0 03:29:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:29:01 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')]} 03:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:01 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')]} 03:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:01 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 0x10d450b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e0d78990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, '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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 03:29:01 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 03:29:01 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 03:29:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:29:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:29:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:29:01 INFO - 'HOME': '/Users/cltbld', 03:29:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:29:01 INFO - 'LOGNAME': 'cltbld', 03:29:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:01 INFO - 'MOZ_NO_REMOTE': '1', 03:29:01 INFO - 'NO_EM_RESTART': '1', 03:29:01 INFO - 'PAGER': '/bin/cat', 03:29:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:29:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:01 INFO - 'PWD': '/builds/slave/test', 03:29:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:29:01 INFO - 'SHELL': '/bin/bash', 03:29:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:29:01 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:29:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:29:01 INFO - 'USER': 'cltbld', 03:29:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:29:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:29:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:01 INFO - 'XPC_FLAGS': '0x0', 03:29:01 INFO - 'XPC_SERVICE_NAME': '0', 03:29:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:29:02 INFO - Cleaning up... 03:29:02 INFO - Return code: 0 03:29:02 INFO - Running post-action listener: _resource_record_post_action 03:29:02 INFO - Running post-action listener: _start_resource_monitoring 03:29:02 INFO - Starting resource monitoring. 03:29:02 INFO - [mozharness: 2017-05-26 10:29:02.089461Z] Finished create-virtualenv step (success) 03:29:02 INFO - [mozharness: 2017-05-26 10:29:02.090447Z] Running install step. 03:29:02 INFO - Running pre-action listener: _resource_record_pre_action 03:29:02 INFO - Running main action method: install 03:29:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:29:02 INFO - Reading from file tmpfile_stdout 03:29:02 INFO - Detecting whether we're running mozinstall >=1.0... 03:29:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:29:02 INFO - Reading from file tmpfile_stdout 03:29:02 INFO - Output received: 03:29:02 INFO - Usage: mozinstall [options] installer 03:29:02 INFO - Options: 03:29:02 INFO - -h, --help show this help message and exit 03:29:02 INFO - -d DEST, --destination=DEST 03:29:02 INFO - Directory to install application into. [default: 03:29:02 INFO - "/builds/slave/test"] 03:29:02 INFO - --app=APP Application being installed. [default: firefox] 03:29:02 INFO - mkdir: /builds/slave/test/build/application 03:29:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:29:24 INFO - Reading from file tmpfile_stdout 03:29:24 INFO - Output received: 03:29:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:29:24 INFO - Running post-action listener: _resource_record_post_action 03:29:24 INFO - [mozharness: 2017-05-26 10:29:24.005967Z] Finished install step (success) 03:29:24 INFO - [mozharness: 2017-05-26 10:29:24.006125Z] Running run-tests step. 03:29:24 INFO - Running pre-action listener: _resource_record_pre_action 03:29:24 INFO - Running main action method: run_tests 03:29:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:29:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:29:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:29:24 INFO - 2017-05-26 03:29:24.023 screenresolution[1635:7429] starting screenresolution argv=screenresolution get 03:29:24 INFO - 2017-05-26 03:29:24.026 screenresolution[1635:7429] Display 0: 1600x1200x32@60 03:29:24 INFO - 2017-05-26 03:29:24.032 screenresolution[1636:7431] starting screenresolution argv=screenresolution list 03:29:24 INFO - Available Modes on Display 0 03:29:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:29:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:29:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:29:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:29:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:29:24 INFO - Intel Iris: 03:29:24 INFO - Chipset Model: Intel Iris 03:29:24 INFO - Type: GPU 03:29:24 INFO - Bus: Built-In 03:29:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:29:24 INFO - Vendor: Intel (0x8086) 03:29:24 INFO - Device ID: 0x0a2e 03:29:24 INFO - Revision ID: 0x0009 03:29:24 INFO - Displays: 03:29:24 INFO - Display: 03:29:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:29:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:29:24 INFO - Display Serial Number: 1600x1200 60 03:29:24 INFO - Main Display: Yes 03:29:24 INFO - Mirror: Off 03:29:24 INFO - Online: Yes 03:29:24 INFO - Rotation: Supported 03:29:24 INFO - Return code: 0 03:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:29:24 INFO - Python 2.7.3 03:29:24 INFO - Return code: 0 03:29:24 INFO - grabbing minidump binary from tooltool 03:29:24 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')]} 03:29:24 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 0x10d160d30>, '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 0x10d114db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d155be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:29:24 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 03:29:24 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 03:29:24 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 03:29:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:29:24 INFO - Return code: 0 03:29:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:29:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:29:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:29:24 INFO - ENV: RUST_BACKTRACE is now 1 03:29:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:29:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:29:24 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/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 03:29:24 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/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0385 --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 03:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pPa8ufzzo9/Render', 03:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:29:24 INFO - 'HOME': '/Users/cltbld', 03:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:29:24 INFO - 'LOGNAME': 'cltbld', 03:29:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:29:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:24 INFO - 'MOZ_NO_REMOTE': '1', 03:29:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:29:24 INFO - 'NO_EM_RESTART': '1', 03:29:24 INFO - 'PAGER': '/bin/cat', 03:29:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:24 INFO - 'PWD': '/builds/slave/test', 03:29:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:29:24 INFO - 'RUST_BACKTRACE': '1', 03:29:24 INFO - 'SHELL': '/bin/bash', 03:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T8SfYBx2TU/Listeners', 03:29:24 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 03:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:29:24 INFO - 'USER': 'cltbld', 03:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:24 INFO - 'XPC_FLAGS': '0x0', 03:29:24 INFO - 'XPC_SERVICE_NAME': '0', 03:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:29:24 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/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 03:29:24 INFO - mozversion application_buildid: 20170526020734 03:29:24 INFO - mozversion application_changeset: bedd24174ff464c4318db7224aa47ba84c25abec 03:29:24 INFO - mozversion application_display_name: Nightly 03:29:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:29:24 INFO - mozversion application_name: Firefox 03:29:24 INFO - mozversion application_remotingname: firefox 03:29:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:29:24 INFO - mozversion application_vendor: Mozilla 03:29:24 INFO - mozversion application_version: 55.0a1 03:29:24 INFO - mozversion platform_buildid: 20170526020734 03:29:24 INFO - mozversion platform_changeset: bedd24174ff464c4318db7224aa47ba84c25abec 03:29:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:29:24 INFO - mozversion platform_version: 55.0a1 03:29:24 INFO - using testdate: 1495794564 03:29:24 INFO - actual date: 1495794564 03:29:24 INFO - starting webserver on 'localhost:49222' 03:29:24 INFO - SUITE-START | Running 2 tests 03:29:24 INFO - TEST-START | dromaeo_css 03:29:24 INFO - operating with platform_type : mac_ 03:29:24 INFO - Initialising browser for dromaeo_css test... 03:29:24 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpWasTBF/profile 03:29:24 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 03:29:58 INFO - TEST-INFO | 1648: killed by SIGTERM 03:29:58 INFO - Browser initialized. 03:29:58 INFO - Running cycle 1/1 for dromaeo_css test... 03:29:58 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpWasTBF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:00 INFO - PID 1656 | 2017-05-26 03:30:00.304 plugin-container[1657:7975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 03:30:00 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:30:00 INFO - PID 1656 | 2017-05-26 03:30:00.304 plugin-container[1657:7975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 03:30:00 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:30:00 INFO - PID 1656 | 2017-05-26 03:30:00.304 plugin-container[1657:7975] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:30:01 INFO - PID 1656 | 2017-05-26 03:30:01.032 plugin-container[1659:8026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x942b, name = 'com.apple.tsm.portname' 03:30:01 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:30:01 INFO - PID 1656 | 2017-05-26 03:30:01.033 plugin-container[1659:8026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 03:30:01 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:30:01 INFO - PID 1656 | 2017-05-26 03:30:01.033 plugin-container[1659:8026] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:30:10 INFO - PID 1656 | RSS: Main: 242888704 03:30:10 INFO - PID 1656 | 03:33:12 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 03:33:14 INFO - PID 1656 | RSS: Main: 256675840 03:33:14 INFO - PID 1656 | 03:36:16 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 03:36:16 INFO - PID 1656 | RSS: Main: 270708736 03:36:16 INFO - PID 1656 | 03:39:18 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 03:39:19 INFO - PID 1656 | RSS: Main: 269758464 03:39:19 INFO - PID 1656 | 03:42:20 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 03:42:21 INFO - PID 1656 | RSS: Main: 276008960 03:42:21 INFO - PID 1656 | 03:45:24 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 03:45:24 INFO - PID 1656 | RSS: Main: 277106688 03:45:24 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 03:48:26 INFO - PID 1656 | RSS: Main: 278614016 03:48:26 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | __start_tp_report 03:48:26 INFO - PID 1656 | _x_x_mozilla_page_load 03:48:26 INFO - PID 1656 | _x_x_mozilla_page_load_details 03:48:26 INFO - PID 1656 | |i|pagename|runs| 03:48:26 INFO - PID 1656 | |0;dojo.html;3589.766665166764;3601.621829707881;3632.364336241157;3640.3629364861144;3652.6164752700956;6161.8151455456355;6165.352637973016;6228.9377106228985;6242.937570624293;6254.530910181732;5763;6186.659733714644;6193.225846769154;6240.531960102999;6252.84367890803;5368.91946620801;5411.242426060355;5423.430539793324;5432.619716619838;5456.645318054334;5484.204790305404;5538.501534861862;5553.583481238903;5590.021746194399;5617.241672374218;5372.2210279509645;5481.588880833913;5490.533304669108;5552.86117847053;5571;5427.267318911936;5467.507924286833;5474.671519708791;5506.366767821712;5511.779528818843;5436.939796739637;5457.890842183134;5480.3971563128025;5484.314460692413;5500.28496295479;5075.3402057732255;6129.693515324216;6249.531285153632;6253.6560489173535;6257.061440783873;6407.199100112486;6522.641254730988;6627.469802415795;6694.732210711566;6700.698468568926;6111.694415279218;6853.9314606853795;6870.519063665545;6876.484263680244;6878.7248510059535;5956.285245770492;6344.873102537924;6348.904766428507;6358;6370.936290637127;6724.260331363546;6831.9658401707675;6863.588184708933;6888.552244104118;6984.371406573331;6216.627002379872;6372.936270637327;6407.679616019181;6465.321141280192;6672.633005184762;6708.262091169968;6816.182058153054;6817.931820681829;6824.761133360308;6828.078209441662;6685;6698.363655452724;6716.093327400836;6722.66386680664;6734;6467.159269294962;6550.0829883816305;6591.472682185414;6728.932710672831;6750.29121942189;5365.141577347606;5383.300170977748;5425.023495770799;5425.321834770653;5428.511433970962;4802.951970480321;4808.06242782654;4850.660453768202;4862.95137048625;4870.464248932614;6249.7187626556915;6271.247450305992;6279.811605651837;6333.050042493663;6335.968320158462;6670.533062685662;6730.360615741497;6796.864062718718;6827.965860170667;6833.829154271183;5295.841124766186;6195.411435913581;6283.905741413882;6332.778352757724;6334.081558174039;5921.082232254007;5979.342272350038;5983.341832398432;5998.640081595118;5999.730012149464;6748.257691653914;6765.594064356154;6878.65606719652;6907.585544867324;6939.167299924042;6791.66041697923;6813.250542440427;6855.280195579592;6884.724611015503;6907.654617269217;6980.476464265099;7016.36852683261;7024.051753013277;7024.894626580503;7046.1192350956135;6529.412352888263;6608.867822643617;6610;6665.133532640726;6704.262531121475;6846.418054465227;6882;6897.7585784497305;6907.34380233877;6910.7581234656545;6367.044943258548;6678.065070890177;6680.365365290289;6681.732730690718;6695.230048544285;6488.091667166506;6537.5096867734155;6596.406323430914;6624.6025238485845;6652.567583107082;6475.708593113227;6490.545661803629;6519.445847103049;6521.934780652323;6528.445082168069;6608.4052435281055;6628.83427914306;6631.237407698177;6634.170728658918;6635.0710900473005;6272.529560282906;6362.522810789117;6371.55399122057;6423.29343772194;6446.194225721785 03:48:26 INFO - PID 1656 | |1;ext.html;766.0807031562123;796.4146352430964;817.7342363731785;1194.0805579703624;1266.6453393049949;38602.03494912625;39142.608573914215;39186.60813391865;39214.41178382326;39233.41149882754;39377.60622393782;39541.80229098858;39699.809005729876;39742.60257397433;39804;7781.610919454033;7967.840643187133;8287.337013038972;8519.062903080656;8523.105073967237;8331.33349332055;8428.325733941301;8431;8431.512703752407;8432.15678432158;8364.58177091146;8388.203120703523;8459.365547583955;8468.830623387528;8490.575471226444;8315.916840831575;8396.782466542347;8435.70475033377;8443.873341899845;8469.872951905727;7053.68258428372;8286.46138001034;8324.958375208145;8342.416030877841;8344.332453403775;36362.45456318157;36478;36606.816965915;36655.450168247495;36763.63236367629;3600.891973240807;3618.5657721073376;3639.4176931690804;3642.289753498069;3643.8360273787744;29592.81628734848;29952.70047299521;29965.700342996508;29970.100896973127;30009;3043.6956304369387;3102.1314032071054;3110.8289044102567;3119.407312610597;3126.3434678717495;246803;249344;273280.63359683275;296737;299348;47090;47380.526194738304;47391;47475.525244747114;47512.812179695786;45541.772291138994;48089;49200.50799491959;49212.26181607279;55229.72385138129;14319;14320.785188222186;14477.78283325751;14483.058601190889;14512.782308265385;11360.318380897173;11406.543738250542;11527.942360288145;11566.710832229093;11577.305361678325;14031.368588413547;14085.225312607905;14102.788458173136;14130.858691412954;14250.287485625884;6170.969145154246;6242.781502647385;6250.031245157008;6257.874842503215;6264.498840092781;963.7542426681291;987.6543209876486;1014.0873214107215;1040.3341861208833;1062.6918193723734;19354.903225483784;24420.389490262885;24744.381390465383;24802.50394992126;29006.27484312909;973.5521660036321;1058.6559368205365;1081.6700906223678;1092.2627226622158;1119.3899324867966;12009.939950300193;12250.877491225152;12323.137380383287;12324.568640097732;12372.566960156351;314934;318512;318943;319845.80154198623;320049;50998;51283.97432051338;51370.74314628403;51412.74293628508;51434.74282628563;1431.9761370620056;1451.2090910453617;1475.4098360655823;1489.7765335199806;1508.2835653064706;23330.883345583843;23352.883235583715;23632.29103126909;23692.52614947657;23798.167064152673;37197.44203836999;37390.439143412324;37397.626023739416;37493;37557.62442375541;267035;279320;279900;281160;281507;31390.901318453743;32429.02712918616;32687;32724;32930.84742034017;41705.95735106646;42308.57691423046;42377.152456951306;42423.36364954466;42482.150356993305;27678;28855.711442886142;29436.558451622815;29975.400491989603;30197.01036400523;27981.32112073282;29084.563731544466;29689.327960482795;29691.85154074216;29974.85012574923;28654.85672571624;35300;35792.28415431625;35940.56237750565;35983.46024809578;24066;27623.72376276292;29411.705882940896;29993;30339;21497.78502214958;23290.417739556648;23712.644310335705;23732.881335593902;23962.40093997664 03:48:26 INFO - PID 1656 | |2;jquery.html;423.59123445874724;424.1061544592646;434.1274039181247;437.2632114886417;443.9200943830111;16528.42150524729;19267.710984335212;21280.57438851222;22037;22136.114555417786;22322.330330090084;23036.19373321934;23042.539149217006;23527.647085293735;23738.88130559349;5903.88192236155;6197.194364732579;6199.69001549923;6208.379162083801;6222.782202622909;5867.823965281048;6078.604890682113;6093.78671746489;6137.0487574425815;6202.069689546559;6050.42520960508;6089.512838972893;6092.725827337758;6096.512279017668;6110.144579758838;5862.736176872051;6071.848203794896;6075.483583895374;6080.087986801993;6131.570790044654;5380.488853558905;5426.565874730051;5442.374126975409;5444.346678398618;5448.591355648342;26538.6019209712;26815.65921703907;26825.061122860607;27007;27136.321591960357;2088.457001179689;2155.5257843274453;2166.9566608667897;2181.520065585569;2182.9890850545644;15561.455349062842;16233.782466314955;17869;17934.372296969545;18163.818361816346;2127;2147.602693501707;2183.585118827418;2185.093186327688;2190.3647942096773;139939.30030349884;167142.164289179;167176;169064;170575;10788.460576971276;11710;12593.30736809466;12778.23330600167;12834.743305133845;13712.794308085387;16243.756343654855;16541.007539547667;16653;16653.583660408585;8739;8885.466871987572;8905.910940890637;8928.776780580538;8944.776380590538;8264.256216940505;8398.160183981552;8414.495130292202;8426.368022398223;8492.74521764348;7964.367304702638;8183.345332373515;8195.016598008277;8239.835203296021;8258.091609922903;1864.1704441523664;2166.3825809644177;2179.2917301876955;2189.813865821423;2323.117215458149;851.2636569367453;916.6791622932054;941.9058094190526;960.1550635440767;967.970960871175;20306.796932030786;20870;21003.159873604884;21079.8946005269;21438.464038399165;907.8515677667756;979.6081567373108;983.3313346924017;1000.284796370584;1012.9189664826796;8860.64557417709;10576.841347379795;10652.254342195973;10773.569057237622;10824.971627695513;561250;561400;567211;569377.0622937756;571564;101012;131096;136606;141172;142668;1075.7579544602402;1194.0865238092865;1202.675277675023;1243.4031664800946;1257.9245245285313;16408.835911641207;21765.347039588836;21822;22080.88959555192;22130.88934555317;55964.44035559755;56244.718776405854;56335.873282534936;56555.43444565502;57675.711621441624;79295;79433.20566794259;79510.61468155964;80791.38417231505;80856.19143808486;8805;8892.244159246278;9033.548322583712;9042.00537940839;9277.62889484413;13753.699112212797;14183.716325673222;14351.780713276577;14681.19253441071;14922.701545969237;11845;12026.639200823989;12084.577039803564;12166.209196402206;12292.508299667914;11049.613263535737;11253.480780094582;11482.425878706199;11648.650540483799;11797.469113889723;11808.527658893892;12132.636020919386;12227.877721222674;12312;12352.567660131852;7741.496802707806;8034.55809930436;8320.209580090124;8778.561071946506;8963.507007114671;9849.753756156153;11769.352685602375;11782.941085294862;12228.32744199042;12272.570460033856 03:48:26 INFO - PID 1656 | |3;mootools.html;1376.4838185680371;1433.4982756035395;1434.8449498153982;1438.4461982136875;1446.8914831387644;11368.033717134043;11899.940500297496;11902.702432439204;11938.224015439013;12250.448729807158;11929.522819087226;12143.967762740178;12148.210366326204;12150.69623259419;12160.513579456834;2572.9099481518106;2613.882375293107;2616.3459135216194;2617.2933308006827;2619.0833208377107;2540.3776074861685;2580.883860226285;2582.122078493312;2590.6632137822153;2597.6752905886765;2533.2147034419295;2559.923202303934;2566.4353842154696;2568.3579105223694;2569.79441644668;2417.9637305440433;2498.100683753847;2526.343150780792;2537.796976241896;2548.4011257354505;2458.987705061463;2519.143491212978;2524.507720994407;2578.226532040399;2595.429005618761;11602.477888494954;11612.245204061708;11716.121290903217;11724.179307448481;11756.353400562966;6439.93560064398;6592.703328350236;6695.296993815677;6696.5647232929705;6697.330266973312;4132.152908653718;4310.892227694301;4359.934600980988;4361.389405483234;4392.758398288093;1217.4825699077944;1256.378092844039;1260.3383223807482;1268.0869773762877;1280.3982128399637;13866.959978001589;14184.361703723358;16333.34666613334;16416.261268242957;16442.917785411115;5463.31708536433;5470.370907345706;5665.093585026376;5683.488486036276;5691.800786972436;9741.9025809741;11661.300321980709;11727.706807329885;11868.465919033491;11874.821877671842;6294.9685251574365;6521.869562608721;6526.021096835512;6538.084668146464;6552;6070.271567411939;6174.413430724074;6217.564770466024;6223.159873417031;6231.345708700521;3717.4052151655605;4315.913681726348;4352.412424322676;4436.8447104351835;4438.4451943507065;1254.9498020079277;1273.4587800184884;1286.7362190750873;1291.380137533989;1294.1717300927414;648.2998361769278;765.4565258666298;789.1004255149135;794.940379471542;797.0395673213764;6825.419839313714;6853.348931851466;6881.793546193622;6889.311068893171;7057.611831349325;818.6970820796344;910.226307638502;920.5719340506696;928.8420968435382;952.8856537215579;3690.33573956685;3957.6240257175523;4204.285271503852;4406.471223453142;4440.800163992628;14524.709505810037;15074.170920599472;15278.006929549543;15301.08193508393;15304.08175509473;13438.596842094752;14977.550673479813;15149.545513634608;15185.544433667008;15209.923950380398;8488.915110848937;8522.999999999876;8586.184312490303;8616.827663446822;8637;6184.456578652177;6990.8951365728535;6991.265917078634;7092.432605391659;7135.179454362815;13405.463781448632;13639.181649101085;13888.791668125188;13888.930555347159;13891.722165556834;13805.86194138086;13812.309384530934;13916.860831391961;14043.648908777363;14185.858141418867;7067.6112813795735;8278.461899976483;8412.284955778827;8521.233089022018;8528.573571321533;8980.595873185854;10704.839427408751;10797.784044318912;10813.189010824062;10837.133029357476;8887.37788354809;9219.17027467531;9274.629014839331;9277.67528136512;9333.57998890038;7535.397168226638;9090.090990900857;9100.817983640423;9161.77095572616;9220.354575179941;7871.685132594632;8071.152528984372;8115.715949941724;8131.105578386491;8142.83714325722;5982.957153405444;6009.819705408845;6087.543434242361;6167.5374346925055;6227.19046523949;5182.766775495036;5729.8567535811935;6140.078988151813;6175.475084617858;6219.502439804714 03:48:26 INFO - PID 1656 | |4;prototype.html;355.7065421027652;391.40311025685844;392.97249192556535;394.5679480968341;403.517796233501;6246.656433896134;6329.430351268391;6365.618062916223;6397.968010159949;6444.194475690539;5181.766820493078;5907.0548712206055;6299.212598425197;6319.336469670688;6319.968400158004;3752.7748335099914;4330.826766929335;4474.9328760068465;4585.747783871886;4602.401687780583;4021.1957608478274;4358.411614432042;4591.357209990604;4593.311003349508;4593.356930029799;4297.548757380477;4576.244919588264;4582.243929751604;4582.793774280149;4589.311603259504;3631.2500934989507;3898.571157172709;4522.5929666330185;4546.840555658319;4557.225271703818;3482.7736197147356;3874.496315478998;3892.5328960524635;3894.4937158169255;3909.706771992112;6041.72812223451;6123.357047509992;6173.59871608344;6185.257769067741;6185.597921143353;5271.102403134907;5450.427705090948;5495.80167932826;5507.834764957018;5520.641158324696;6683.331666833326;6750.426213771835;6819.283975182584;6819.863602727967;6820.897686534706;3678.576963649161;4376.8838946068545;4384.298512238059;4421.38100665907;4425.800838962255;5340.759665815048;7001.264867188974;7036.6481675916;7174.529221509631;7213.2786721327975;4446.5331140230455;4489.169503641837;4492.595666390041;4494.303382975579;4504.6486054183615;4577.336286238543;4589.54104589545;4590.632749380042;4593.402857628487;4601.493835678073;5310.840674779685;5357.7053262070185;5415.675059496442;5443.700596467233;5489.066858634042;5261.842144735664;5845.298564172326;6804.55770374924;6822.317768223138;6823.897641535381;1295.6113166050147;1411.696485255675;1571.230097252338;1573.819010813771;1574.3151728997923;967.1730670276953;971.2812518736225;1012.9240306976859;1024.416082832793;1031.67429852639;5573.832785016456;5853.443922827325;6146.139540464339;6401.9039714404325;6411.67941602918;6836.5214434989075;7029.964850175818;7037.894431583428;7059.6117213552725;7088.964555177191;5303.098473259555;5622.437756224344;5938.406159384027;6279.4662453691035;6374.362563743682;3203.7276831469585;3267.39553182662;3550.4851796489365;3648.6351364863303;3695.427208782643;5474.260974768435;5554.805581804618;6030.090511298951;6789.524733268624;6937.167539895245;5721.39925307845;6221.9422698141425;6644.66776661165;6901.89647155283;6983.406410455168;5516.586256030733;5667.914981275202;6341.397567231105;7094.326039026284;7118.786436406916;6146.016637338004;7076.256993015865;7083.929160708327;7100.396466300423;7103.644817758988;6123.663198524124;6124.14262003311;6321.96839015802;6697.698603562754;6820.215675197416;6846.520743547904;7050.541714788727;7292.671829767779;7307.525010874276;7318.451116166202;3847.442120892511;3999.24014437256;4503.459584849839;4516.435445569304;4516.570925762047;5764.394738552392;5795.3625101237985;7068.540544864567;7106.218315985134;7152.606606636685;6797.762078327235;6816.284290149463;6837.418819400208;6842.794716158523;6844.452443804584;5722.599418040697;6238.752249550017;6391.616503009835;6634.635095069624;6885.380315771605;5824.912626310524;6645.637644283007;6655.633940133145;6657.234418041987;6661.702457283675;6249.625022498664;6540.247871494648;6619.238787539494;6737.764178253738;6889.689963951734;5203.614259241666;5727.713614319184;6449.161608990801;6599.868002640016;6653.334666533308 03:48:26 INFO - PID 1656 | |5;yui.html;762.6110683551389;833.3583140981441;849.6771226933768;868.496272162146;875.3828550871636;14671.486497972573;15230.314635841387;16827.663446731058;16965.321387144515;17220.569485762862;17351.219195136247;17974;18012.63974720505;18069.819301807012;18077.096145192754;759.4949358676487;989.5547003848261;991.2317953585956;1004.5830980143231;1007.1942446043173;1000.4147573669412;1006.4464544500532;1006.4967516241879;1007.1338648762058;1007.3754272351152;1034.1364960258193;1049.5539395756773;1050.9527071281811;1053.5838343854173;1053.66282789507;1034.5603118674571;1042.3276986343765;1045.8065257927308;1047.3140093238903;1047.7852040331713;774.8992630957996;775.4068137874531;775.6470805783355;776.9728059517946;777.0093131257692;15211.771823422658;15551.989120707118;15560.299786509635;15598.298076586581;17784.3775467858;17395.391161309293;17488.038157901312;17648.294068237385;17753.467395978143;17999.100044997696;14495.782563261559;14586.4894728684;14826.629334266621;14841.480548180762;14851.480198193012;3526.894193174209;3582.802945837978;3604.2250916053176;3612.4581312803025;3647.1611529348397;113918;123469.38265308706;136732;138551.30724346315;138636.9204461934;13174.670633234246;13572.728545429036;13626.386812593457;13634.318284085755;13844.584662460142;25551.744482555307;26145.607815882777;26416.339591509983;26612.60081098785;26617.3345666356;6404.48764098871;6699.9950007499265;6748.865022699519;7678.424118191158;7800.882986755203;3837.4435706822355;3915.275674000319;3919.941200881989;3926.7251292409255;3927.7446965947124;4058.3100872851687;4076.0828813516723;4116.938245926313;4148.62662360383;4154.231467178582;881.4358810361374;936.6113063078882;941.7692665297046;945.3666043750703;945.8155659646303;633.088352772016;638.6742761191733;648.9772857949949;656.1207981305074;668.2448832818884;10785.78428431427;11621.941890290664;12224.3276619785;12286;12344.81482777759;582.6678793087984;596.7676747516365;633.4520639646764;640.3180612647516;663.7842701122155;4502;5593.657522194725;5686;5790.36306006339;5870.207521984563;140736;142024.5797542032;142483.2875835614;142716.28641856933;142776;105604;106558;106956.46521767341;107228.46385768021;110999;94639.52680236692;95739;96193.41872394031;96844.03155968491;97083.54374684385;12303.261804291771;12585.05612079116;12885.420156093185;12934.870651293742;12954;41384.793076034424;41651.583484164774;42242.78878605587;42513.14973700447;42634;79070;79149;79483.2051679499;81121.59439203002;82804.58597706973;16741.246643901297;16808.991460512407;17000.149992500574;17034.914825425793;17079.48761537156;16030.95798773076;16735.49793506197;18223;18225.179866905757;18780.906095469436;14207.644808879448;17036.574085647957;17093.743593845855;17417.912910435367;17619.823801761817;16711.415100471426;16820;17179.828201717824;17284;17324.220410081325;16527.586810329838;17652.029138396996;17686;17693.93836369822;17820.376286829898;11797;11837.348945808064;11960.521579136737;12483.12618116723;12532.122751407314;13462.798058028942;14267.357968891218;14405.06367086136;14440.711185776436;14444.988850780344 03:48:26 INFO - PID 1656 | __end_tp_report 03:48:26 INFO - PID 1656 | __start_cc_report 03:48:26 INFO - PID 1656 | _x_x_mozilla_cycle_collect,395 03:48:26 INFO - PID 1656 | __end_cc_report 03:48:26 INFO - PID 1656 | __startTimestamp1495795706809__endTimestamp 03:48:26 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | ------- Summary: start ------- 03:48:26 INFO - PID 1656 | Number of tests: 6 03:48:26 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | [#0] dojo.html Cycles:165 Average:6202.59 Median:6407.20 stddev:725.85 (11.3%) stddev-sans-first:698.53 03:48:26 INFO - PID 1656 | Values: 3589.8 3601.6 3632.4 3640.4 3652.6 6161.8 6165.4 6228.9 6242.9 6254.5 5763.0 6186.7 6193.2 6240.5 6252.8 5368.9 5411.2 5423.4 5432.6 5456.6 5484.2 5538.5 5553.6 5590.0 5617.2 5372.2 5481.6 5490.5 5552.9 5571.0 5427.3 5467.5 5474.7 5506.4 5511.8 5436.9 5457.9 5480.4 5484.3 5500.3 5075.3 6129.7 6249.5 6253.7 6257.1 6407.2 6522.6 6627.5 6694.7 6700.7 6111.7 6853.9 6870.5 6876.5 6878.7 5956.3 6344.9 6348.9 6358.0 6370.9 6724.3 6832.0 6863.6 6888.6 6984.4 6216.6 6372.9 6407.7 6465.3 6672.6 6708.3 6816.2 6817.9 6824.8 6828.1 6685.0 6698.4 6716.1 6722.7 6734.0 6467.2 6550.1 6591.5 6728.9 6750.3 5365.1 5383.3 5425.0 5425.3 5428.5 4803.0 4808.1 4850.7 4863.0 4870.5 6249.7 6271.2 6279.8 6333.1 6336.0 6670.5 6730.4 6796.9 6828.0 6833.8 5295.8 6195.4 6283.9 6332.8 6334.1 5921.1 5979.3 5983.3 5998.6 5999.7 6748.3 6765.6 6878.7 6907.6 6939.2 6791.7 6813.3 6855.3 6884.7 6907.7 6980.5 7016.4 7024.1 7024.9 7046.1 6529.4 6608.9 6610.0 6665.1 6704.3 6846.4 6882.0 6897.8 6907.3 6910.8 6367.0 6678.1 6680.4 6681.7 6695.2 6488.1 6537.5 6596.4 6624.6 6652.6 6475.7 6490.5 6519.4 6521.9 6528.4 6608.4 6628.8 6631.2 6634.2 6635.1 6272.5 6362.5 6371.6 6423.3 6446.2 03:48:26 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | [#1] ext.html Cycles:180 Average:43846.20 Median:176239.01 stddev:76205.04 (43.2%) stddev-sans-first:76350.17 03:48:26 INFO - PID 1656 | Values: 766.1 796.4 817.7 1194.1 1266.6 38602.0 39142.6 39186.6 39214.4 39233.4 39377.6 39541.8 39699.8 39742.6 39804.0 7781.6 7967.8 8287.3 8519.1 8523.1 8331.3 8428.3 8431.0 8431.5 8432.2 8364.6 8388.2 8459.4 8468.8 8490.6 8315.9 8396.8 8435.7 8443.9 8469.9 7053.7 8286.5 8325.0 8342.4 8344.3 36362.5 36478.0 36606.8 36655.5 36763.6 3600.9 3618.6 3639.4 3642.3 3643.8 29592.8 29952.7 29965.7 29970.1 30009.0 3043.7 3102.1 3110.8 3119.4 3126.3 246803.0 249344.0 273280.6 296737.0 299348.0 47090.0 47380.5 47391.0 47475.5 47512.8 45541.8 48089.0 49200.5 49212.3 55229.7 14319.0 14320.8 14477.8 14483.1 14512.8 11360.3 11406.5 11527.9 11566.7 11577.3 14031.4 14085.2 14102.8 14130.9 14250.3 6171.0 6242.8 6250.0 6257.9 6264.5 963.8 987.7 1014.1 1040.3 1062.7 19354.9 24420.4 24744.4 24802.5 29006.3 973.6 1058.7 1081.7 1092.3 1119.4 12009.9 12250.9 12323.1 12324.6 12372.6 314934.0 318512.0 318943.0 319845.8 320049.0 50998.0 51284.0 51370.7 51412.7 51434.7 1432.0 1451.2 1475.4 1489.8 1508.3 23330.9 23352.9 23632.3 23692.5 23798.2 37197.4 37390.4 37397.6 37493.0 37557.6 267035.0 279320.0 279900.0 281160.0 281507.0 31390.9 32429.0 32687.0 32724.0 32930.8 41706.0 42308.6 42377.2 42423.4 42482.2 27678.0 28855.7 29436.6 29975.4 30197.0 27981.3 29084.6 29689.3 29691.9 29974.9 28654.9 35300.0 35792.3 35940.6 35983.5 24066.0 27623.7 29411.7 29993.0 30339.0 21497.8 23290.4 23712.6 23732.9 23962.4 03:48:26 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | [#2] jquery.html Cycles:180 Average:36329.62 Median:23039.37 stddev:96186.90 (417.5%) stddev-sans-first:96418.95 03:48:26 INFO - PID 1656 | Values: 423.6 424.1 434.1 437.3 443.9 16528.4 19267.7 21280.6 22037.0 22136.1 22322.3 23036.2 23042.5 23527.6 23738.9 5903.9 6197.2 6199.7 6208.4 6222.8 5867.8 6078.6 6093.8 6137.0 6202.1 6050.4 6089.5 6092.7 6096.5 6110.1 5862.7 6071.8 6075.5 6080.1 6131.6 5380.5 5426.6 5442.4 5444.3 5448.6 26538.6 26815.7 26825.1 27007.0 27136.3 2088.5 2155.5 2167.0 2181.5 2183.0 15561.5 16233.8 17869.0 17934.4 18163.8 2127.0 2147.6 2183.6 2185.1 2190.4 139939.3 167142.2 167176.0 169064.0 170575.0 10788.5 11710.0 12593.3 12778.2 12834.7 13712.8 16243.8 16541.0 16653.0 16653.6 8739.0 8885.5 8905.9 8928.8 8944.8 8264.3 8398.2 8414.5 8426.4 8492.7 7964.4 8183.3 8195.0 8239.8 8258.1 1864.2 2166.4 2179.3 2189.8 2323.1 851.3 916.7 941.9 960.2 968.0 20306.8 20870.0 21003.2 21079.9 21438.5 907.9 979.6 983.3 1000.3 1012.9 8860.6 10576.8 10652.3 10773.6 10825.0 561250.0 561400.0 567211.0 569377.1 571564.0 101012.0 131096.0 136606.0 141172.0 142668.0 1075.8 1194.1 1202.7 1243.4 1257.9 16408.8 21765.3 21822.0 22080.9 22130.9 55964.4 56244.7 56335.9 56555.4 57675.7 79295.0 79433.2 79510.6 80791.4 80856.2 8805.0 8892.2 9033.5 9042.0 9277.6 13753.7 14183.7 14351.8 14681.2 14922.7 11845.0 12026.6 12084.6 12166.2 12292.5 11049.6 11253.5 11482.4 11648.7 11797.5 11808.5 12132.6 12227.9 12312.0 12352.6 7741.5 8034.6 8320.2 8778.6 8963.5 9849.8 11769.4 11782.9 12228.3 12272.6 03:48:26 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | [#3] mootools.html Cycles:180 Average:7085.73 Median:4044.89 stddev:4456.74 (110.2%) stddev-sans-first:4448.60 03:48:26 INFO - PID 1656 | Values: 1376.5 1433.5 1434.8 1438.4 1446.9 11368.0 11899.9 11902.7 11938.2 12250.4 11929.5 12144.0 12148.2 12150.7 12160.5 2572.9 2613.9 2616.3 2617.3 2619.1 2540.4 2580.9 2582.1 2590.7 2597.7 2533.2 2559.9 2566.4 2568.4 2569.8 2418.0 2498.1 2526.3 2537.8 2548.4 2459.0 2519.1 2524.5 2578.2 2595.4 11602.5 11612.2 11716.1 11724.2 11756.4 6439.9 6592.7 6695.3 6696.6 6697.3 4132.2 4310.9 4359.9 4361.4 4392.8 1217.5 1256.4 1260.3 1268.1 1280.4 13867.0 14184.4 16333.3 16416.3 16442.9 5463.3 5470.4 5665.1 5683.5 5691.8 9741.9 11661.3 11727.7 11868.5 11874.8 6295.0 6521.9 6526.0 6538.1 6552.0 6070.3 6174.4 6217.6 6223.2 6231.3 3717.4 4315.9 4352.4 4436.8 4438.4 1254.9 1273.5 1286.7 1291.4 1294.2 648.3 765.5 789.1 794.9 797.0 6825.4 6853.3 6881.8 6889.3 7057.6 818.7 910.2 920.6 928.8 952.9 3690.3 3957.6 4204.3 4406.5 4440.8 14524.7 15074.2 15278.0 15301.1 15304.1 13438.6 14977.6 15149.5 15185.5 15209.9 8488.9 8523.0 8586.2 8616.8 8637.0 6184.5 6990.9 6991.3 7092.4 7135.2 13405.5 13639.2 13888.8 13888.9 13891.7 13805.9 13812.3 13916.9 14043.6 14185.9 7067.6 8278.5 8412.3 8521.2 8528.6 8980.6 10704.8 10797.8 10813.2 10837.1 8887.4 9219.2 9274.6 9277.7 9333.6 7535.4 9090.1 9100.8 9161.8 9220.4 7871.7 8071.2 8115.7 8131.1 8142.8 5983.0 6009.8 6087.5 6167.5 6227.2 5182.8 5729.9 6140.1 6175.5 6219.5 03:48:26 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | [#4] prototype.html Cycles:180 Average:5332.87 Median:5849.37 stddev:1710.76 (29.2%) stddev-sans-first:1674.28 03:48:26 INFO - PID 1656 | Values: 355.7 391.4 393.0 394.6 403.5 6246.7 6329.4 6365.6 6398.0 6444.2 5181.8 5907.1 6299.2 6319.3 6320.0 3752.8 4330.8 4474.9 4585.7 4602.4 4021.2 4358.4 4591.4 4593.3 4593.4 4297.5 4576.2 4582.2 4582.8 4589.3 3631.3 3898.6 4522.6 4546.8 4557.2 3482.8 3874.5 3892.5 3894.5 3909.7 6041.7 6123.4 6173.6 6185.3 6185.6 5271.1 5450.4 5495.8 5507.8 5520.6 6683.3 6750.4 6819.3 6819.9 6820.9 3678.6 4376.9 4384.3 4421.4 4425.8 5340.8 7001.3 7036.6 7174.5 7213.3 4446.5 4489.2 4492.6 4494.3 4504.6 4577.3 4589.5 4590.6 4593.4 4601.5 5310.8 5357.7 5415.7 5443.7 5489.1 5261.8 5845.3 6804.6 6822.3 6823.9 1295.6 1411.7 1571.2 1573.8 1574.3 967.2 971.3 1012.9 1024.4 1031.7 5573.8 5853.4 6146.1 6401.9 6411.7 6836.5 7030.0 7037.9 7059.6 7089.0 5303.1 5622.4 5938.4 6279.5 6374.4 3203.7 3267.4 3550.5 3648.6 3695.4 5474.3 5554.8 6030.1 6789.5 6937.2 5721.4 6221.9 6644.7 6901.9 6983.4 5516.6 5667.9 6341.4 7094.3 7118.8 6146.0 7076.3 7083.9 7100.4 7103.6 6123.7 6124.1 6322.0 6697.7 6820.2 6846.5 7050.5 7292.7 7307.5 7318.5 3847.4 3999.2 4503.5 4516.4 4516.6 5764.4 5795.4 7068.5 7106.2 7152.6 6797.8 6816.3 6837.4 6842.8 6844.5 5722.6 6238.8 6391.6 6634.6 6885.4 5824.9 6645.6 6655.6 6657.2 6661.7 6249.6 6540.2 6619.2 6737.8 6889.7 5203.6 5727.7 6449.2 6599.9 6653.3 03:48:26 INFO - PID 1656 | 03:48:26 INFO - PID 1656 | [#5] yui.html Cycles:180 Average:24703.21 Median:17553.93 stddev:36927.79 (210.4%) stddev-sans-first:36987.63 03:48:26 INFO - PID 1656 | Values: 762.6 833.4 849.7 868.5 875.4 14671.5 15230.3 16827.7 16965.3 17220.6 17351.2 17974.0 18012.6 18069.8 18077.1 759.5 989.6 991.2 1004.6 1007.2 1000.4 1006.4 1006.5 1007.1 1007.4 1034.1 1049.6 1051.0 1053.6 1053.7 1034.6 1042.3 1045.8 1047.3 1047.8 774.9 775.4 775.6 777.0 777.0 15211.8 15552.0 15560.3 15598.3 17784.4 17395.4 17488.0 17648.3 17753.5 17999.1 14495.8 14586.5 14826.6 14841.5 14851.5 3526.9 3582.8 3604.2 3612.5 3647.2 113918.0 123469.4 136732.0 138551.3 138636.9 13174.7 13572.7 13626.4 13634.3 13844.6 25551.7 26145.6 26416.3 26612.6 26617.3 6404.5 6700.0 6748.9 7678.4 7800.9 3837.4 3915.3 3919.9 3926.7 3927.7 4058.3 4076.1 4116.9 4148.6 4154.2 881.4 936.6 941.8 945.4 945.8 633.1 638.7 649.0 656.1 668.2 10785.8 11621.9 12224.3 12286.0 12344.8 582.7 596.8 633.5 640.3 663.8 4502.0 5593.7 5686.0 5790.4 5870.2 140736.0 142024.6 142483.3 142716.3 142776.0 105604.0 106558.0 106956.5 107228.5 110999.0 94639.5 95739.0 96193.4 96844.0 97083.5 12303.3 12585.1 12885.4 12934.9 12954.0 41384.8 41651.6 42242.8 42513.1 42634.0 79070.0 79149.0 79483.2 81121.6 82804.6 16741.2 16809.0 17000.1 17034.9 17079.5 16031.0 16735.5 18223.0 18225.2 18780.9 14207.6 17036.6 17093.7 17417.9 17619.8 16711.4 16820.0 17179.8 17284.0 17324.2 16527.6 17652.0 17686.0 17693.9 17820.4 11797.0 11837.3 11960.5 12483.1 12532.1 13462.8 14267.4 14405.1 14440.7 14445.0 03:48:26 INFO - PID 1656 | -------- Summary: end -------- 03:48:26 INFO - PID 1656 | 03:48:27 INFO - TEST-INFO | 1656: exit 0 03:48:27 INFO - TEST-OK | dromaeo_css | took 1142622ms 03:48:27 INFO - TEST-START | kraken 03:48:27 INFO - operating with platform_type : mac_ 03:48:27 INFO - Initialising browser for kraken test... 03:48:27 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpPuZ_Ws/profile 03:48:27 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 03:49:00 INFO - TEST-INFO | 1688: killed by SIGTERM 03:49:00 INFO - Browser initialized. 03:49:00 INFO - Running cycle 1/1 for kraken test... 03:49:00 INFO - TEST-INFO | started process 1692 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpPuZ_Ws/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:02 INFO - PID 1692 | 2017-05-26 03:49:02.774 plugin-container[1693:10641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984f, name = 'com.apple.tsm.portname' 03:49:02 INFO - PID 1692 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:49:02 INFO - PID 1692 | 2017-05-26 03:49:02.774 plugin-container[1693:10641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 03:49:02 INFO - PID 1692 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:49:02 INFO - PID 1692 | 2017-05-26 03:49:02.774 plugin-container[1693:10641] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:49:03 INFO - PID 1692 | 2017-05-26 03:49:03.339 plugin-container[1694:10683] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 03:49:03 INFO - PID 1692 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:49:03 INFO - PID 1692 | 2017-05-26 03:49:03.339 plugin-container[1694:10683] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c03, name = 'com.apple.CFPasteboardClient' 03:49:03 INFO - PID 1692 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:49:03 INFO - PID 1692 | 2017-05-26 03:49:03.339 plugin-container[1694:10683] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:49:12 INFO - PID 1692 | RSS: Main: 242720768 03:49:12 INFO - PID 1692 | 03:49:15 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:16 INFO - PID 1692 | RSS: Main: 257265664 03:49:16 INFO - PID 1692 | 03:49:19 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 03:49:19 INFO - PID 1692 | RSS: Main: 266035200 03:49:19 INFO - PID 1692 | 03:49:23 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 03:49:23 INFO - PID 1692 | RSS: Main: 269107200 03:49:23 INFO - PID 1692 | 03:49:25 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 03:49:26 INFO - PID 1692 | RSS: Main: 270012416 03:49:26 INFO - PID 1692 | 03:49:28 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:49:28 INFO - PID 1692 | RSS: Main: 270483456 03:49:28 INFO - PID 1692 | 03:49:31 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:32 INFO - PID 1692 | RSS: Main: 275554304 03:49:32 INFO - PID 1692 | 03:49:35 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 03:49:36 INFO - PID 1692 | RSS: Main: 278749184 03:49:36 INFO - PID 1692 | 03:49:39 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 03:49:39 INFO - PID 1692 | RSS: Main: 277176320 03:49:39 INFO - PID 1692 | 03:49:41 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:49:42 INFO - PID 1692 | RSS: Main: 278491136 03:49:42 INFO - PID 1692 | 03:49:43 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:49:44 INFO - PID 1692 | RSS: Main: 278319104 03:49:44 INFO - PID 1692 | 03:49:46 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:47 INFO - PID 1692 | RSS: Main: 278351872 03:49:47 INFO - PID 1692 | 03:49:50 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:50 INFO - PID 1692 | RSS: Main: 278151168 03:49:50 INFO - PID 1692 | 03:49:53 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:54 INFO - PID 1692 | RSS: Main: 278192128 03:49:54 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 03:49:56 INFO - PID 1692 | RSS: Main: 280113152 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | __start_tp_report 03:49:56 INFO - PID 1692 | _x_x_mozilla_page_load 03:49:56 INFO - PID 1692 | _x_x_mozilla_page_load_details 03:49:56 INFO - PID 1692 | |i|pagename|runs| 03:49:56 INFO - PID 1692 | |0;ai-astar;100;114;114;114;114;115;113;113;164;114 03:49:56 INFO - PID 1692 | |1;audio-beat-detection;144;119;144;159;141;96;142;147;156;127 03:49:56 INFO - PID 1692 | |2;audio-dft;200;200;197;228;213;210;198;202;203;252 03:49:56 INFO - PID 1692 | |3;audio-fft;84;58;61;86;59;61;59;59;71;57 03:49:56 INFO - PID 1692 | |4;audio-oscillator;85;68;85;70;68;69;86;69;68;92 03:49:56 INFO - PID 1692 | |5;imaging-gaussian-blur;91;92;105;134;85;135;79;133;134;95 03:49:56 INFO - PID 1692 | |6;imaging-darkroom;97;99;98;98;99;97;99;97;98;98 03:49:56 INFO - PID 1692 | |7;imaging-desaturate;92;91;91;94;95;130;84;140;143;90 03:49:56 INFO - PID 1692 | |8;json-parse-financial;51;51;50;53;53;53;53;52;54;51 03:49:56 INFO - PID 1692 | |9;json-stringify-tinderbox;57;56;61;58;61;59;60;57;55;57 03:49:56 INFO - PID 1692 | |10;stanford-crypto-aes;85;105;86;89;89;85;87;87;138;89 03:49:56 INFO - PID 1692 | |11;stanford-crypto-ccm;140;142;140;137;142;140;143;151;177;158 03:49:56 INFO - PID 1692 | |12;stanford-crypto-pbkdf2;192;183;190;187;186;188;182;186;182;195 03:49:56 INFO - PID 1692 | |13;stanford-crypto-sha256-iterative;69;69;66;67;67;69;72;77;99;92 03:49:56 INFO - PID 1692 | __end_tp_report 03:49:56 INFO - PID 1692 | __start_cc_report 03:49:56 INFO - PID 1692 | _x_x_mozilla_cycle_collect,1050 03:49:56 INFO - PID 1692 | __end_cc_report 03:49:56 INFO - PID 1692 | __startTimestamp1495795796538__endTimestamp 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | ------- Summary: start ------- 03:49:56 INFO - PID 1692 | Number of tests: 14 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#0] ai-astar Cycles:10 Average:117.50 Median:114.00 stddev:16.92 (14.8%) stddev-sans-first:16.72 03:49:56 INFO - PID 1692 | Values: 100.0 114.0 114.0 114.0 114.0 115.0 113.0 113.0 164.0 114.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:145.50 stddev:18.82 (12.9%) stddev-sans-first:19.81 03:49:56 INFO - PID 1692 | Values: 144.0 119.0 144.0 159.0 141.0 96.0 142.0 147.0 156.0 127.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#2] audio-dft Cycles:10 Average:210.30 Median:206.50 stddev:17.39 (8.4%) stddev-sans-first:18.04 03:49:56 INFO - PID 1692 | Values: 200.0 200.0 197.0 228.0 213.0 210.0 198.0 202.0 203.0 252.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#3] audio-fft Cycles:10 Average:65.50 Median:61.00 stddev:11.00 (18.0%) stddev-sans-first:9.41 03:49:56 INFO - PID 1692 | Values: 84.0 58.0 61.0 86.0 59.0 61.0 59.0 59.0 71.0 57.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.50 stddev:9.68 (12.5%) stddev-sans-first:9.71 03:49:56 INFO - PID 1692 | Values: 85.0 68.0 85.0 70.0 68.0 69.0 86.0 69.0 68.0 92.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#5] imaging-gaussian-blur Cycles:10 Average:108.30 Median:82.00 stddev:23.09 (28.2%) stddev-sans-first:23.63 03:49:56 INFO - PID 1692 | Values: 91.0 92.0 105.0 134.0 85.0 135.0 79.0 133.0 134.0 95.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:98.00 stddev:0.82 (0.8%) stddev-sans-first:0.78 03:49:56 INFO - PID 1692 | Values: 97.0 99.0 98.0 98.0 99.0 97.0 99.0 97.0 98.0 98.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#7] imaging-desaturate Cycles:10 Average:105.00 Median:91.00 stddev:22.95 (25.2%) stddev-sans-first:23.86 03:49:56 INFO - PID 1692 | Values: 92.0 91.0 91.0 94.0 95.0 130.0 84.0 140.0 143.0 90.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:53.00 stddev:1.29 (2.4%) stddev-sans-first:1.30 03:49:56 INFO - PID 1692 | Values: 51.0 51.0 50.0 53.0 53.0 53.0 53.0 52.0 54.0 51.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:58.50 stddev:2.08 (3.6%) stddev-sans-first:2.17 03:49:56 INFO - PID 1692 | Values: 57.0 56.0 61.0 58.0 61.0 59.0 60.0 57.0 55.0 57.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#10] stanford-crypto-aes Cycles:10 Average:94.00 Median:87.00 stddev:16.52 (19.0%) stddev-sans-first:17.20 03:49:56 INFO - PID 1692 | Values: 85.0 105.0 86.0 89.0 89.0 85.0 87.0 87.0 138.0 89.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:142.50 stddev:12.25 (8.6%) stddev-sans-first:12.73 03:49:56 INFO - PID 1692 | Values: 140.0 142.0 140.0 137.0 142.0 140.0 143.0 151.0 177.0 158.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.10 Median:187.50 stddev:4.31 (2.3%) stddev-sans-first:4.19 03:49:56 INFO - PID 1692 | Values: 192.0 183.0 190.0 187.0 186.0 188.0 182.0 186.0 182.0 195.0 03:49:56 INFO - PID 1692 | 03:49:56 INFO - PID 1692 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:70.50 stddev:11.52 (16.3%) stddev-sans-first:12.03 03:49:56 INFO - PID 1692 | Values: 69.0 69.0 66.0 67.0 67.0 69.0 72.0 77.0 99.0 92.0 03:49:56 INFO - PID 1692 | -------- Summary: end -------- 03:49:56 INFO - PID 1692 | 03:49:57 INFO - TEST-INFO | 1692: exit 0 03:49:57 INFO - TEST-OK | kraken | took 90006ms 03:49:57 INFO - SUITE-END | took 1232s 03:49:57 INFO - Completed test suite (00:20:32) 03:49:57 INFO - javascript benchmark 03:49:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7927.790298582513, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3589.766665166764, 3601.621829707881, 3632.364336241157, 3640.3629364861144, 3652.6164752700956, 6161.8151455456355, 6165.352637973016, 6228.9377106228985, 6242.937570624293, 6254.530910181732, 5763.0, 6186.659733714644, 6193.225846769154, 6240.531960102999, 6252.84367890803, 5368.91946620801, 5411.242426060355, 5423.430539793324, 5432.619716619838, 5456.645318054334, 5484.204790305404, 5538.501534861862, 5553.583481238903, 5590.021746194399, 5617.241672374218, 5372.2210279509645, 5481.588880833913, 5490.533304669108, 5552.86117847053, 5571.0, 5427.267318911936, 5467.507924286833, 5474.671519708791, 5506.366767821712, 5511.779528818843, 5436.939796739637, 5457.890842183134, 5480.3971563128025, 5484.314460692413, 5500.28496295479, 5075.3402057732255, 6129.693515324216, 6249.531285153632, 6253.6560489173535, 6257.061440783873, 6407.199100112486, 6522.641254730988, 6627.469802415795, 6694.732210711566, 6700.698468568926, 6111.694415279218, 6853.9314606853795, 6870.519063665545, 6876.484263680244, 6878.7248510059535, 5956.285245770492, 6344.873102537924, 6348.904766428507, 6358.0, 6370.936290637127, 6724.260331363546, 6831.9658401707675, 6863.588184708933, 6888.552244104118, 6984.371406573331, 6216.627002379872, 6372.936270637327, 6407.679616019181, 6465.321141280192, 6672.633005184762, 6708.262091169968, 6816.182058153054, 6817.931820681829, 6824.761133360308, 6828.078209441662, 6685.0, 6698.363655452724, 6716.093327400836, 6722.66386680664, 6734.0, 6467.159269294962, 6550.0829883816305, 6591.472682185414, 6728.932710672831, 6750.29121942189, 5365.141577347606, 5383.300170977748, 5425.023495770799, 5425.321834770653, 5428.511433970962, 4802.951970480321, 4808.06242782654, 4850.660453768202, 4862.95137048625, 4870.464248932614, 6249.7187626556915, 6271.247450305992, 6279.811605651837, 6333.050042493663, 6335.968320158462, 6670.533062685662, 6730.360615741497, 6796.864062718718, 6827.965860170667, 6833.829154271183, 5295.841124766186, 6195.411435913581, 6283.905741413882, 6332.778352757724, 6334.081558174039, 5921.082232254007, 5979.342272350038, 5983.341832398432, 5998.640081595118, 5999.730012149464, 6748.257691653914, 6765.594064356154, 6878.65606719652, 6907.585544867324, 6939.167299924042, 6791.66041697923, 6813.250542440427, 6855.280195579592, 6884.724611015503, 6907.654617269217, 6980.476464265099, 7016.36852683261, 7024.051753013277, 7024.894626580503, 7046.1192350956135, 6529.412352888263, 6608.867822643617, 6610.0, 6665.133532640726, 6704.262531121475, 6846.418054465227, 6882.0, 6897.7585784497305, 6907.34380233877, 6910.7581234656545, 6367.044943258548, 6678.065070890177, 6680.365365290289, 6681.732730690718, 6695.230048544285, 6488.091667166506, 6537.5096867734155, 6596.406323430914, 6624.6025238485845, 6652.567583107082, 6475.708593113227, 6490.545661803629, 6519.445847103049, 6521.934780652323, 6528.445082168069, 6608.4052435281055, 6628.83427914306, 6631.237407698177, 6634.170728658918, 6635.0710900473005, 6272.529560282906, 6362.522810789117, 6371.55399122057, 6423.29343772194, 6446.194225721785], "value": 6154.682933857869, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.0807031562123, 796.4146352430964, 817.7342363731785, 1194.0805579703624, 1266.6453393049949, 38602.03494912625, 39142.608573914215, 39186.60813391865, 39214.41178382326, 39233.41149882754, 39377.60622393782, 39541.80229098858, 39699.809005729876, 39742.60257397433, 39804.0, 7781.610919454033, 7967.840643187133, 8287.337013038972, 8519.062903080656, 8523.105073967237, 8331.33349332055, 8428.325733941301, 8431.0, 8431.512703752407, 8432.15678432158, 8364.58177091146, 8388.203120703523, 8459.365547583955, 8468.830623387528, 8490.575471226444, 8315.916840831575, 8396.782466542347, 8435.70475033377, 8443.873341899845, 8469.872951905727, 7053.68258428372, 8286.46138001034, 8324.958375208145, 8342.416030877841, 8344.332453403775, 36362.45456318157, 36478.0, 36606.816965915, 36655.450168247495, 36763.63236367629, 3600.891973240807, 3618.5657721073376, 3639.4176931690804, 3642.289753498069, 3643.8360273787744, 29592.81628734848, 29952.70047299521, 29965.700342996508, 29970.100896973127, 30009.0, 3043.6956304369387, 3102.1314032071054, 3110.8289044102567, 3119.407312610597, 3126.3434678717495, 246803.0, 249344.0, 273280.63359683275, 296737.0, 299348.0, 47090.0, 47380.526194738304, 47391.0, 47475.525244747114, 47512.812179695786, 45541.772291138994, 48089.0, 49200.50799491959, 49212.26181607279, 55229.72385138129, 14319.0, 14320.785188222186, 14477.78283325751, 14483.058601190889, 14512.782308265385, 11360.318380897173, 11406.543738250542, 11527.942360288145, 11566.710832229093, 11577.305361678325, 14031.368588413547, 14085.225312607905, 14102.788458173136, 14130.858691412954, 14250.287485625884, 6170.969145154246, 6242.781502647385, 6250.031245157008, 6257.874842503215, 6264.498840092781, 963.7542426681291, 987.6543209876486, 1014.0873214107215, 1040.3341861208833, 1062.6918193723734, 19354.903225483784, 24420.389490262885, 24744.381390465383, 24802.50394992126, 29006.27484312909, 973.5521660036321, 1058.6559368205365, 1081.6700906223678, 1092.2627226622158, 1119.3899324867966, 12009.939950300193, 12250.877491225152, 12323.137380383287, 12324.568640097732, 12372.566960156351, 314934.0, 318512.0, 318943.0, 319845.80154198623, 320049.0, 50998.0, 51283.97432051338, 51370.74314628403, 51412.74293628508, 51434.74282628563, 1431.9761370620056, 1451.2090910453617, 1475.4098360655823, 1489.7765335199806, 1508.2835653064706, 23330.883345583843, 23352.883235583715, 23632.29103126909, 23692.52614947657, 23798.167064152673, 37197.44203836999, 37390.439143412324, 37397.626023739416, 37493.0, 37557.62442375541, 267035.0, 279320.0, 279900.0, 281160.0, 281507.0, 31390.901318453743, 32429.02712918616, 32687.0, 32724.0, 32930.84742034017, 41705.95735106646, 42308.57691423046, 42377.152456951306, 42423.36364954466, 42482.150356993305, 27678.0, 28855.711442886142, 29436.558451622815, 29975.400491989603, 30197.01036400523, 27981.32112073282, 29084.563731544466, 29689.327960482795, 29691.85154074216, 29974.85012574923, 28654.85672571624, 35300.0, 35792.28415431625, 35940.56237750565, 35983.46024809578, 24066.0, 27623.72376276292, 29411.705882940896, 29993.0, 30339.0, 21497.78502214958, 23290.417739556648, 23712.644310335705, 23732.881335593902, 23962.40093997664], "value": 17264.909783921037, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.59123445874724, 424.1061544592646, 434.1274039181247, 437.2632114886417, 443.9200943830111, 16528.42150524729, 19267.710984335212, 21280.57438851222, 22037.0, 22136.114555417786, 22322.330330090084, 23036.19373321934, 23042.539149217006, 23527.647085293735, 23738.88130559349, 5903.88192236155, 6197.194364732579, 6199.69001549923, 6208.379162083801, 6222.782202622909, 5867.823965281048, 6078.604890682113, 6093.78671746489, 6137.0487574425815, 6202.069689546559, 6050.42520960508, 6089.512838972893, 6092.725827337758, 6096.512279017668, 6110.144579758838, 5862.736176872051, 6071.848203794896, 6075.483583895374, 6080.087986801993, 6131.570790044654, 5380.488853558905, 5426.565874730051, 5442.374126975409, 5444.346678398618, 5448.591355648342, 26538.6019209712, 26815.65921703907, 26825.061122860607, 27007.0, 27136.321591960357, 2088.457001179689, 2155.5257843274453, 2166.9566608667897, 2181.520065585569, 2182.9890850545644, 15561.455349062842, 16233.782466314955, 17869.0, 17934.372296969545, 18163.818361816346, 2127.0, 2147.602693501707, 2183.585118827418, 2185.093186327688, 2190.3647942096773, 139939.30030349884, 167142.164289179, 167176.0, 169064.0, 170575.0, 10788.460576971276, 11710.0, 12593.30736809466, 12778.23330600167, 12834.743305133845, 13712.794308085387, 16243.756343654855, 16541.007539547667, 16653.0, 16653.583660408585, 8739.0, 8885.466871987572, 8905.910940890637, 8928.776780580538, 8944.776380590538, 8264.256216940505, 8398.160183981552, 8414.495130292202, 8426.368022398223, 8492.74521764348, 7964.367304702638, 8183.345332373515, 8195.016598008277, 8239.835203296021, 8258.091609922903, 1864.1704441523664, 2166.3825809644177, 2179.2917301876955, 2189.813865821423, 2323.117215458149, 851.2636569367453, 916.6791622932054, 941.9058094190526, 960.1550635440767, 967.970960871175, 20306.796932030786, 20870.0, 21003.159873604884, 21079.8946005269, 21438.464038399165, 907.8515677667756, 979.6081567373108, 983.3313346924017, 1000.284796370584, 1012.9189664826796, 8860.64557417709, 10576.841347379795, 10652.254342195973, 10773.569057237622, 10824.971627695513, 561250.0, 561400.0, 567211.0, 569377.0622937756, 571564.0, 101012.0, 131096.0, 136606.0, 141172.0, 142668.0, 1075.7579544602402, 1194.0865238092865, 1202.675277675023, 1243.4031664800946, 1257.9245245285313, 16408.835911641207, 21765.347039588836, 21822.0, 22080.88959555192, 22130.88934555317, 55964.44035559755, 56244.718776405854, 56335.873282534936, 56555.43444565502, 57675.711621441624, 79295.0, 79433.20566794259, 79510.61468155964, 80791.38417231505, 80856.19143808486, 8805.0, 8892.244159246278, 9033.548322583712, 9042.00537940839, 9277.62889484413, 13753.699112212797, 14183.716325673222, 14351.780713276577, 14681.19253441071, 14922.701545969237, 11845.0, 12026.639200823989, 12084.577039803564, 12166.209196402206, 12292.508299667914, 11049.613263535737, 11253.480780094582, 11482.425878706199, 11648.650540483799, 11797.469113889723, 11808.527658893892, 12132.636020919386, 12227.877721222674, 12312.0, 12352.567660131852, 7741.496802707806, 8034.55809930436, 8320.209580090124, 8778.561071946506, 8963.507007114671, 9849.753756156153, 11769.352685602375, 11782.941085294862, 12228.32744199042, 12272.570460033856], "value": 10436.067220517787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.4838185680371, 1433.4982756035395, 1434.8449498153982, 1438.4461982136875, 1446.8914831387644, 11368.033717134043, 11899.940500297496, 11902.702432439204, 11938.224015439013, 12250.448729807158, 11929.522819087226, 12143.967762740178, 12148.210366326204, 12150.69623259419, 12160.513579456834, 2572.9099481518106, 2613.882375293107, 2616.3459135216194, 2617.2933308006827, 2619.0833208377107, 2540.3776074861685, 2580.883860226285, 2582.122078493312, 2590.6632137822153, 2597.6752905886765, 2533.2147034419295, 2559.923202303934, 2566.4353842154696, 2568.3579105223694, 2569.79441644668, 2417.9637305440433, 2498.100683753847, 2526.343150780792, 2537.796976241896, 2548.4011257354505, 2458.987705061463, 2519.143491212978, 2524.507720994407, 2578.226532040399, 2595.429005618761, 11602.477888494954, 11612.245204061708, 11716.121290903217, 11724.179307448481, 11756.353400562966, 6439.93560064398, 6592.703328350236, 6695.296993815677, 6696.5647232929705, 6697.330266973312, 4132.152908653718, 4310.892227694301, 4359.934600980988, 4361.389405483234, 4392.758398288093, 1217.4825699077944, 1256.378092844039, 1260.3383223807482, 1268.0869773762877, 1280.3982128399637, 13866.959978001589, 14184.361703723358, 16333.34666613334, 16416.261268242957, 16442.917785411115, 5463.31708536433, 5470.370907345706, 5665.093585026376, 5683.488486036276, 5691.800786972436, 9741.9025809741, 11661.300321980709, 11727.706807329885, 11868.465919033491, 11874.821877671842, 6294.9685251574365, 6521.869562608721, 6526.021096835512, 6538.084668146464, 6552.0, 6070.271567411939, 6174.413430724074, 6217.564770466024, 6223.159873417031, 6231.345708700521, 3717.4052151655605, 4315.913681726348, 4352.412424322676, 4436.8447104351835, 4438.4451943507065, 1254.9498020079277, 1273.4587800184884, 1286.7362190750873, 1291.380137533989, 1294.1717300927414, 648.2998361769278, 765.4565258666298, 789.1004255149135, 794.940379471542, 797.0395673213764, 6825.419839313714, 6853.348931851466, 6881.793546193622, 6889.311068893171, 7057.611831349325, 818.6970820796344, 910.226307638502, 920.5719340506696, 928.8420968435382, 952.8856537215579, 3690.33573956685, 3957.6240257175523, 4204.285271503852, 4406.471223453142, 4440.800163992628, 14524.709505810037, 15074.170920599472, 15278.006929549543, 15301.08193508393, 15304.08175509473, 13438.596842094752, 14977.550673479813, 15149.545513634608, 15185.544433667008, 15209.923950380398, 8488.915110848937, 8522.999999999876, 8586.184312490303, 8616.827663446822, 8637.0, 6184.456578652177, 6990.8951365728535, 6991.265917078634, 7092.432605391659, 7135.179454362815, 13405.463781448632, 13639.181649101085, 13888.791668125188, 13888.930555347159, 13891.722165556834, 13805.86194138086, 13812.309384530934, 13916.860831391961, 14043.648908777363, 14185.858141418867, 7067.6112813795735, 8278.461899976483, 8412.284955778827, 8521.233089022018, 8528.573571321533, 8980.595873185854, 10704.839427408751, 10797.784044318912, 10813.189010824062, 10837.133029357476, 8887.37788354809, 9219.17027467531, 9274.629014839331, 9277.67528136512, 9333.57998890038, 7535.397168226638, 9090.090990900857, 9100.817983640423, 9161.77095572616, 9220.354575179941, 7871.685132594632, 8071.152528984372, 8115.715949941724, 8131.105578386491, 8142.83714325722, 5982.957153405444, 6009.819705408845, 6087.543434242361, 6167.5374346925055, 6227.19046523949, 5182.766775495036, 5729.8567535811935, 6140.078988151813, 6175.475084617858, 6219.502439804714], "value": 5378.0717623115825, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.7065421027652, 391.40311025685844, 392.97249192556535, 394.5679480968341, 403.517796233501, 6246.656433896134, 6329.430351268391, 6365.618062916223, 6397.968010159949, 6444.194475690539, 5181.766820493078, 5907.0548712206055, 6299.212598425197, 6319.336469670688, 6319.968400158004, 3752.7748335099914, 4330.826766929335, 4474.9328760068465, 4585.747783871886, 4602.401687780583, 4021.1957608478274, 4358.411614432042, 4591.357209990604, 4593.311003349508, 4593.356930029799, 4297.548757380477, 4576.244919588264, 4582.243929751604, 4582.793774280149, 4589.311603259504, 3631.2500934989507, 3898.571157172709, 4522.5929666330185, 4546.840555658319, 4557.225271703818, 3482.7736197147356, 3874.496315478998, 3892.5328960524635, 3894.4937158169255, 3909.706771992112, 6041.72812223451, 6123.357047509992, 6173.59871608344, 6185.257769067741, 6185.597921143353, 5271.102403134907, 5450.427705090948, 5495.80167932826, 5507.834764957018, 5520.641158324696, 6683.331666833326, 6750.426213771835, 6819.283975182584, 6819.863602727967, 6820.897686534706, 3678.576963649161, 4376.8838946068545, 4384.298512238059, 4421.38100665907, 4425.800838962255, 5340.759665815048, 7001.264867188974, 7036.6481675916, 7174.529221509631, 7213.2786721327975, 4446.5331140230455, 4489.169503641837, 4492.595666390041, 4494.303382975579, 4504.6486054183615, 4577.336286238543, 4589.54104589545, 4590.632749380042, 4593.402857628487, 4601.493835678073, 5310.840674779685, 5357.7053262070185, 5415.675059496442, 5443.700596467233, 5489.066858634042, 5261.842144735664, 5845.298564172326, 6804.55770374924, 6822.317768223138, 6823.897641535381, 1295.6113166050147, 1411.696485255675, 1571.230097252338, 1573.819010813771, 1574.3151728997923, 967.1730670276953, 971.2812518736225, 1012.9240306976859, 1024.416082832793, 1031.67429852639, 5573.832785016456, 5853.443922827325, 6146.139540464339, 6401.9039714404325, 6411.67941602918, 6836.5214434989075, 7029.964850175818, 7037.894431583428, 7059.6117213552725, 7088.964555177191, 5303.098473259555, 5622.437756224344, 5938.406159384027, 6279.4662453691035, 6374.362563743682, 3203.7276831469585, 3267.39553182662, 3550.4851796489365, 3648.6351364863303, 3695.427208782643, 5474.260974768435, 5554.805581804618, 6030.090511298951, 6789.524733268624, 6937.167539895245, 5721.39925307845, 6221.9422698141425, 6644.66776661165, 6901.89647155283, 6983.406410455168, 5516.586256030733, 5667.914981275202, 6341.397567231105, 7094.326039026284, 7118.786436406916, 6146.016637338004, 7076.256993015865, 7083.929160708327, 7100.396466300423, 7103.644817758988, 6123.663198524124, 6124.14262003311, 6321.96839015802, 6697.698603562754, 6820.215675197416, 6846.520743547904, 7050.541714788727, 7292.671829767779, 7307.525010874276, 7318.451116166202, 3847.442120892511, 3999.24014437256, 4503.459584849839, 4516.435445569304, 4516.570925762047, 5764.394738552392, 5795.3625101237985, 7068.540544864567, 7106.218315985134, 7152.606606636685, 6797.762078327235, 6816.284290149463, 6837.418819400208, 6842.794716158523, 6844.452443804584, 5722.599418040697, 6238.752249550017, 6391.616503009835, 6634.635095069624, 6885.380315771605, 5824.912626310524, 6645.637644283007, 6655.633940133145, 6657.234418041987, 6661.702457283675, 6249.625022498664, 6540.247871494648, 6619.238787539494, 6737.764178253738, 6889.689963951734, 5203.614259241666, 5727.713614319184, 6449.161608990801, 6599.868002640016, 6653.334666533308], "value": 4794.746139027363, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.6110683551389, 833.3583140981441, 849.6771226933768, 868.496272162146, 875.3828550871636, 14671.486497972573, 15230.314635841387, 16827.663446731058, 16965.321387144515, 17220.569485762862, 17351.219195136247, 17974.0, 18012.63974720505, 18069.819301807012, 18077.096145192754, 759.4949358676487, 989.5547003848261, 991.2317953585956, 1004.5830980143231, 1007.1942446043173, 1000.4147573669412, 1006.4464544500532, 1006.4967516241879, 1007.1338648762058, 1007.3754272351152, 1034.1364960258193, 1049.5539395756773, 1050.9527071281811, 1053.5838343854173, 1053.66282789507, 1034.5603118674571, 1042.3276986343765, 1045.8065257927308, 1047.3140093238903, 1047.7852040331713, 774.8992630957996, 775.4068137874531, 775.6470805783355, 776.9728059517946, 777.0093131257692, 15211.771823422658, 15551.989120707118, 15560.299786509635, 15598.298076586581, 17784.3775467858, 17395.391161309293, 17488.038157901312, 17648.294068237385, 17753.467395978143, 17999.100044997696, 14495.782563261559, 14586.4894728684, 14826.629334266621, 14841.480548180762, 14851.480198193012, 3526.894193174209, 3582.802945837978, 3604.2250916053176, 3612.4581312803025, 3647.1611529348397, 113918.0, 123469.38265308706, 136732.0, 138551.30724346315, 138636.9204461934, 13174.670633234246, 13572.728545429036, 13626.386812593457, 13634.318284085755, 13844.584662460142, 25551.744482555307, 26145.607815882777, 26416.339591509983, 26612.60081098785, 26617.3345666356, 6404.48764098871, 6699.9950007499265, 6748.865022699519, 7678.424118191158, 7800.882986755203, 3837.4435706822355, 3915.275674000319, 3919.941200881989, 3926.7251292409255, 3927.7446965947124, 4058.3100872851687, 4076.0828813516723, 4116.938245926313, 4148.62662360383, 4154.231467178582, 881.4358810361374, 936.6113063078882, 941.7692665297046, 945.3666043750703, 945.8155659646303, 633.088352772016, 638.6742761191733, 648.9772857949949, 656.1207981305074, 668.2448832818884, 10785.78428431427, 11621.941890290664, 12224.3276619785, 12286.0, 12344.81482777759, 582.6678793087984, 596.7676747516365, 633.4520639646764, 640.3180612647516, 663.7842701122155, 4502.0, 5593.657522194725, 5686.0, 5790.36306006339, 5870.207521984563, 140736.0, 142024.5797542032, 142483.2875835614, 142716.28641856933, 142776.0, 105604.0, 106558.0, 106956.46521767341, 107228.46385768021, 110999.0, 94639.52680236692, 95739.0, 96193.41872394031, 96844.03155968491, 97083.54374684385, 12303.261804291771, 12585.05612079116, 12885.420156093185, 12934.870651293742, 12954.0, 41384.793076034424, 41651.583484164774, 42242.78878605587, 42513.14973700447, 42634.0, 79070.0, 79149.0, 79483.2051679499, 81121.59439203002, 82804.58597706973, 16741.246643901297, 16808.991460512407, 17000.149992500574, 17034.914825425793, 17079.48761537156, 16030.95798773076, 16735.49793506197, 18223.0, 18225.179866905757, 18780.906095469436, 14207.644808879448, 17036.574085647957, 17093.743593845855, 17417.912910435367, 17619.823801761817, 16711.415100471426, 16820.0, 17179.828201717824, 17284.0, 17324.220410081325, 16527.586810329838, 17652.029138396996, 17686.0, 17693.93836369822, 17820.376286829898, 11797.0, 11837.348945808064, 11960.521579136737, 12483.12618116723, 12532.122751407314, 13462.798058028942, 14267.357968891218, 14405.06367086136, 14440.711185776436, 14444.988850780344], "value": 8681.28177413379, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1531.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 114.0, 114.0, 114.0, 114.0, 115.0, 113.0, 113.0, 164.0, 114.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 119.0, 144.0, 159.0, 141.0, 96.0, 142.0, 147.0, 156.0, 127.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 197.0, 228.0, 213.0, 210.0, 198.0, 202.0, 203.0, 252.0], "value": 210.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 61.0, 86.0, 59.0, 61.0, 59.0, 59.0, 71.0, 57.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 85.0, 70.0, 68.0, 69.0, 86.0, 69.0, 68.0, 92.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 105.0, 134.0, 85.0, 135.0, 79.0, 133.0, 134.0, 95.0], "value": 108.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 98.0, 98.0, 99.0, 97.0, 99.0, 97.0, 98.0, 98.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 91.0, 94.0, 95.0, 130.0, 84.0, 140.0, 143.0, 90.0], "value": 105.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 53.0, 53.0, 53.0, 53.0, 52.0, 54.0, 51.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 61.0, 58.0, 61.0, 59.0, 60.0, 57.0, 55.0, 57.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 105.0, 86.0, 89.0, 89.0, 85.0, 87.0, 87.0, 138.0, 89.0], "value": 94.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 140.0, 137.0, 142.0, 140.0, 143.0, 151.0, 177.0, 158.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 183.0, 190.0, 187.0, 186.0, 188.0, 182.0, 186.0, 182.0, 195.0], "value": 187.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 66.0, 67.0, 67.0, 69.0, 72.0, 77.0, 99.0, 92.0], "value": 74.7, "unit": "score"}]}]} 03:49:57 INFO - Return code: 0 03:49:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:49:57 INFO - # TBPL SUCCESS # 03:49:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:49:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:49:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:49:57 INFO - 2017-05-26 03:49:57.847 screenresolution[1699:11065] starting screenresolution argv=screenresolution get 03:49:57 INFO - 2017-05-26 03:49:57.849 screenresolution[1699:11065] Display 0: 1600x1200x32@60 03:49:57 INFO - 2017-05-26 03:49:57.855 screenresolution[1700:11068] starting screenresolution argv=screenresolution list 03:49:57 INFO - Available Modes on Display 0 03:49:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:49:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:49:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:49:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:49:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:49:58 INFO - Intel Iris: 03:49:58 INFO - Chipset Model: Intel Iris 03:49:58 INFO - Type: GPU 03:49:58 INFO - Bus: Built-In 03:49:58 INFO - VRAM (Dynamic, Max): 1536 MB 03:49:58 INFO - Vendor: Intel (0x8086) 03:49:58 INFO - Device ID: 0x0a2e 03:49:58 INFO - Revision ID: 0x0009 03:49:58 INFO - Displays: 03:49:58 INFO - Display: 03:49:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:49:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:49:58 INFO - Display Serial Number: 1600x1200 60 03:49:58 INFO - Main Display: Yes 03:49:58 INFO - Mirror: Off 03:49:58 INFO - Online: Yes 03:49:58 INFO - Rotation: Supported 03:49:58 INFO - Return code: 0 03:49:58 INFO - Running post-action listener: _resource_record_post_action 03:49:58 INFO - [mozharness: 2017-05-26 10:49:58.090889Z] Finished run-tests step (success) 03:49:58 INFO - Running post-run listener: _resource_record_post_run 03:49:58 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 40478208; Write bytes: 563845632; Read time: 279; Write time: 1746 03:49:58 INFO - TinderboxPrint: CPU usage
53.2% 03:49:58 INFO - TinderboxPrint: I/O read bytes / time
40,478,208 / 279 03:49:58 INFO - TinderboxPrint: I/O write bytes / time
563,845,632 / 1,746 03:49:58 INFO - TinderboxPrint: CPU idle
2,349.9 (46.8%) 03:49:58 INFO - TinderboxPrint: CPU system
1,017.7 (20.3%) 03:49:58 INFO - TinderboxPrint: CPU user
1,654.1 (32.9%) 03:49:58 INFO - TinderboxPrint: Swap in / out
298,684,416 / 0 03:49:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226431488; Write bytes: 218775552; Read time: 16698; Write time: 448 03:49:58 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 36952576; Write bytes: 318843392; Read time: 246; Write time: 1228 03:49:58 INFO - Running post-run listener: _upload_blobber_files 03:49:58 INFO - Blob upload gear active. 03:49:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49: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 03:49: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'] 03:49: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 03:49:58 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:50:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:00 INFO - (blobuploader) - INFO - Done attempting. 03:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:50:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:00 INFO - (blobuploader) - INFO - Done attempting. 03:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:50:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:02 INFO - (blobuploader) - INFO - Done attempting. 03:50:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:50:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:50:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:04 INFO - (blobuploader) - INFO - Done attempting. 03:50:04 INFO - (blobuploader) - INFO - Iteration through files over. 03:50:04 INFO - Return code: 0 03:50:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:50:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:50:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc6cfc4ca219b2ad7344798ba587381af345d75979b3c4a9af337812c086aabac3cb077c8cf9912db431a491aec6791e809291c2c882eef37562eb386706ece", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f6a4b5b492e711f03171549a6454c9cc3dfabbb63890a950f05822ca0b8c82d70e952d251e0c51b0ad953d928de9d247dc016c0e979c5b98e5a07e75d0e731f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e045ca6e869f3b970fe970e177b45a64c705380730b1d3e3ab7778c4d5441f221b76ca1a2f5ddebcedb9f842ad88994234ba10c54c8fb04be87b263eeaa0999", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ec5e0fd1a5f23596d8158d113dfedaa4318b9b3527aa7a5ef65c5c43beb67a1ceb408f56381169e8f2a1562281d487c819fe77a12fa67ac1cbf2f59dbafed42"} 03:50:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:50:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:50:04 INFO - Contents: 03:50:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc6cfc4ca219b2ad7344798ba587381af345d75979b3c4a9af337812c086aabac3cb077c8cf9912db431a491aec6791e809291c2c882eef37562eb386706ece", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f6a4b5b492e711f03171549a6454c9cc3dfabbb63890a950f05822ca0b8c82d70e952d251e0c51b0ad953d928de9d247dc016c0e979c5b98e5a07e75d0e731f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e045ca6e869f3b970fe970e177b45a64c705380730b1d3e3ab7778c4d5441f221b76ca1a2f5ddebcedb9f842ad88994234ba10c54c8fb04be87b263eeaa0999", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ec5e0fd1a5f23596d8158d113dfedaa4318b9b3527aa7a5ef65c5c43beb67a1ceb408f56381169e8f2a1562281d487c819fe77a12fa67ac1cbf2f59dbafed42"} 03:50:04 INFO - Running post-run listener: copy_logs_to_upload_dir 03:50:04 INFO - Copying logs to upload dir... 03:50:04 INFO - mkdir: /builds/slave/test/build/upload/logs 03:50:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.712499 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-26 03:50:04.346635) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 03:50:04.351324) ========= 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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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/ffc6cfc4ca219b2ad7344798ba587381af345d75979b3c4a9af337812c086aabac3cb077c8cf9912db431a491aec6791e809291c2c882eef37562eb386706ece", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f6a4b5b492e711f03171549a6454c9cc3dfabbb63890a950f05822ca0b8c82d70e952d251e0c51b0ad953d928de9d247dc016c0e979c5b98e5a07e75d0e731f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e045ca6e869f3b970fe970e177b45a64c705380730b1d3e3ab7778c4d5441f221b76ca1a2f5ddebcedb9f842ad88994234ba10c54c8fb04be87b263eeaa0999", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ec5e0fd1a5f23596d8158d113dfedaa4318b9b3527aa7a5ef65c5c43beb67a1ceb408f56381169e8f2a1562281d487c819fe77a12fa67ac1cbf2f59dbafed42"} build_url:https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011115 build_url: 'https://queue.taskcluster.net/v1/task/cK1EQdUSQsO8-lYMagD1mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc6cfc4ca219b2ad7344798ba587381af345d75979b3c4a9af337812c086aabac3cb077c8cf9912db431a491aec6791e809291c2c882eef37562eb386706ece", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f6a4b5b492e711f03171549a6454c9cc3dfabbb63890a950f05822ca0b8c82d70e952d251e0c51b0ad953d928de9d247dc016c0e979c5b98e5a07e75d0e731f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e045ca6e869f3b970fe970e177b45a64c705380730b1d3e3ab7778c4d5441f221b76ca1a2f5ddebcedb9f842ad88994234ba10c54c8fb04be87b263eeaa0999", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ec5e0fd1a5f23596d8158d113dfedaa4318b9b3527aa7a5ef65c5c43beb67a1ceb408f56381169e8f2a1562281d487c819fe77a12fa67ac1cbf2f59dbafed42"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 03:50:04.387556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:50:04.387878) ========= 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.pPa8ufzzo9/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.T8SfYBx2TU/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.005438 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:50:04.436675) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 03:50:04.440014) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 03:50:04.440337) ========= ========= Total master_lag: 0.96 =========