builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0118 starttime: 1464092267.06 results: success (0) buildid: 20160524023517 builduid: ed920d96f71f408395491672eebb0281 revision: 25a5ae2711f38f202ab57b20518d3554072f2f47 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:47.064846) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:47.065284) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:47.065553) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:47.087000) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:47.087374) ========= 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.D62J5PLVMV/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.SwrjMJpjzk/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 --2016-05-24 05:17:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 56.5M=0s 2016-05-24 05:17:47 (56.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088700 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:47.195042) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:47.195349) ========= 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.D62J5PLVMV/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.SwrjMJpjzk/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.028516 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:47.243758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 05:17:47.244130) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 25a5ae2711f38f202ab57b20518d3554072f2f47 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 25a5ae2711f38f202ab57b20518d3554072f2f47 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.D62J5PLVMV/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.SwrjMJpjzk/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 2016-05-24 05:17:47,314 truncating revision to first 12 chars 2016-05-24 05:17:47,314 Setting DEBUG logging. 2016-05-24 05:17:47,314 attempt 1/10 2016-05-24 05:17:47,314 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/25a5ae2711f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 05:17:48,752 unpacking tar archive at: fx-team-25a5ae2711f3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.753099 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 05:17:49.015100) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:49.015422) ========= script_repo_revision: 25a5ae2711f38f202ab57b20518d3554072f2f47 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:49.015881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:49.016163) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 05:17:49.030826) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-24 05:17:49.031106) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.D62J5PLVMV/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.SwrjMJpjzk/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:17:49 INFO - MultiFileLogger online at 20160524 05:17:49 in /builds/slave/test 05:17:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:17:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:17:49 INFO - {'append_to_log': False, 05:17:49 INFO - 'base_work_dir': '/builds/slave/test', 05:17:49 INFO - 'blob_upload_branch': 'Fx-Team', 05:17:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:17:49 INFO - 'branch': 'Fx-Team', 05:17:49 INFO - 'buildbot_json_path': 'buildprops.json', 05:17:49 INFO - 'config_files': ('talos/mac_config.py',), 05:17:49 INFO - 'default_actions': ('clobber', 05:17:49 INFO - 'read-buildbot-config', 05:17:49 INFO - 'download-and-extract', 05:17:49 INFO - 'populate-webroot', 05:17:49 INFO - 'create-virtualenv', 05:17:49 INFO - 'install', 05:17:49 INFO - 'run-tests'), 05:17:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:17:49 INFO - 'download_minidump_stackwalk': True, 05:17:49 INFO - 'download_symbols': 'ondemand', 05:17:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:17:49 INFO - 'tooltool.py': '/tools/tooltool.py', 05:17:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:17:49 INFO - '/tools/misc-python/virtualenv.py')}, 05:17:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:17:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:17:49 INFO - 'installer_path': 'installer.exe', 05:17:49 INFO - 'log_level': 'info', 05:17:49 INFO - 'log_name': 'talos', 05:17:49 INFO - 'log_to_console': True, 05:17:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:17:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:17:49 INFO - 'opt_config_files': (), 05:17:49 INFO - 'pip_index': False, 05:17:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:49 INFO - 'cmd': ('bash', 05:17:49 INFO - '-c', 05:17:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:49 INFO - 'enabled': True, 05:17:49 INFO - 'halt_on_failure': False, 05:17:49 INFO - 'name': 'check_screen_resolution'},), 05:17:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:49 INFO - 'cmd': ('bash', 05:17:49 INFO - '-c', 05:17:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:49 INFO - 'enabled': True, 05:17:49 INFO - 'halt_on_failure': False, 05:17:49 INFO - 'name': 'check_screen_resolution'},), 05:17:49 INFO - 'run_cmd_checks_enabled': True, 05:17:49 INFO - 'sps_profile': False, 05:17:49 INFO - 'sps_profile_interval': 0, 05:17:49 INFO - 'suite': 'dromaeojs', 05:17:49 INFO - 'system_bits': '32', 05:17:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:17:49 INFO - 'title': 't-yosemite-r7-0118', 05:17:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:17:49 INFO - 'use_talos_json': True, 05:17:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:17:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:17:49 INFO - 'work_dir': 'build'} 05:17:49 INFO - ##### 05:17:49 INFO - ##### Running clobber step. 05:17:49 INFO - ##### 05:17:49 INFO - Running pre-action listener: _resource_record_pre_action 05:17:49 INFO - Running main action method: clobber 05:17:49 INFO - rmtree: /builds/slave/test/build 05:17:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:17:51 INFO - Running post-action listener: _resource_record_post_action 05:17:51 INFO - ##### 05:17:51 INFO - ##### Finished clobber step (success) 05:17:51 INFO - ##### 05:17:51 INFO - ##### 05:17:51 INFO - ##### Running read-buildbot-config step. 05:17:51 INFO - ##### 05:17:51 INFO - Running pre-action listener: _resource_record_pre_action 05:17:51 INFO - Running main action method: read_buildbot_config 05:17:51 INFO - Using buildbot properties: 05:17:51 INFO - { 05:17:51 INFO - "project": "", 05:17:51 INFO - "product": "firefox", 05:17:51 INFO - "script_repo_revision": "production", 05:17:51 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:17:51 INFO - "repository": "", 05:17:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:17:51 INFO - "buildid": "20160524023517", 05:17:51 INFO - "pgo_build": "False", 05:17:51 INFO - "basedir": "/builds/slave/test", 05:17:51 INFO - "buildnumber": 18, 05:17:51 INFO - "slavename": "t-yosemite-r7-0118", 05:17:51 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:17:51 INFO - "platform": "yosemite_r7", 05:17:51 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:17:51 INFO - "branch": "fx-team", 05:17:51 INFO - "repo_path": "integration/fx-team", 05:17:51 INFO - "revision": "25a5ae2711f38f202ab57b20518d3554072f2f47", 05:17:51 INFO - "stage_platform": "macosx64", 05:17:51 INFO - "builduid": "ed920d96f71f408395491672eebb0281", 05:17:51 INFO - "slavebuilddir": "test" 05:17:51 INFO - } 05:17:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:17:51 INFO - Running post-action listener: _resource_record_post_action 05:17:51 INFO - ##### 05:17:51 INFO - ##### Finished read-buildbot-config step (success) 05:17:51 INFO - ##### 05:17:51 INFO - ##### 05:17:51 INFO - ##### Running download-and-extract step. 05:17:51 INFO - ##### 05:17:51 INFO - Running pre-action listener: _resource_record_pre_action 05:17:51 INFO - Running main action method: download_and_extract 05:17:51 INFO - mkdir: /builds/slave/test/build/tests 05:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:51 INFO - https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:17:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:17:52 INFO - Downloaded 1199 bytes. 05:17:52 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:52 INFO - Using the following test package requirements: 05:17:52 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:17:52 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:52 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:17:52 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:52 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:17:52 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:52 INFO - u'jsshell-mac.zip'], 05:17:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:52 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:17:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:17:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:52 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:17:52 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:52 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:17:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:52 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:17:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:52 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:17:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:52 INFO - https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:53 INFO - Downloaded 18630549 bytes. 05:17:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:17:56 INFO - Return code: 0 05:17:56 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:57 INFO - Downloaded 18630549 bytes. 05:17:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:01 INFO - Return code: 0 05:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:01 INFO - https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:18:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:18:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:18:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:18:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:18:02 INFO - Downloaded 10840359 bytes. 05:18:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:18:02 INFO - Return code: 0 05:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:02 INFO - https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:18:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:18:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:18:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:18:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:18:06 INFO - Downloaded 110175704 bytes. 05:18:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:18:06 INFO - mkdir: /builds/slave/test/properties 05:18:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:18:06 INFO - Writing to file /builds/slave/test/properties/build_url 05:18:06 INFO - Contents: 05:18:06 INFO - build_url:https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:18:07 INFO - Running post-action listener: _resource_record_post_action 05:18:07 INFO - Running post-action listener: set_extra_try_arguments 05:18:07 INFO - ##### 05:18:07 INFO - ##### Finished download-and-extract step (success) 05:18:07 INFO - ##### 05:18:07 INFO - ##### 05:18:07 INFO - ##### Running populate-webroot step. 05:18:07 INFO - ##### 05:18:07 INFO - Running pre-action listener: _resource_record_pre_action 05:18:07 INFO - Running main action method: populate_webroot 05:18:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:07 INFO - '--tppagecycles', 05:18:07 INFO - '7'], 05:18:07 INFO - 'tests': ['tsvgm']}}, 05:18:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:18:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:18:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:07 INFO - 'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:07 INFO - 'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['damp', 'tps']}, 05:18:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:07 INFO - 'tests': ['damp', 'tps']}, 05:18:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['dromaeo_dom']}, 05:18:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['a11yr', 05:18:07 INFO - 'ts_paint', 05:18:07 INFO - 'tpaint', 05:18:07 INFO - 'sessionrestore', 05:18:07 INFO - 'sessionrestore_no_auto_restore', 05:18:07 INFO - 'tabpaint']}, 05:18:07 INFO - 'other-e10s': {'tests': ['a11yr', 05:18:07 INFO - 'ts_paint', 05:18:07 INFO - 'tpaint', 05:18:07 INFO - 'sessionrestore', 05:18:07 INFO - 'sessionrestore_no_auto_restore', 05:18:07 INFO - 'tabpaint']}, 05:18:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:18:07 INFO - 'ts_paint', 05:18:07 INFO - 'tpaint', 05:18:07 INFO - 'sessionrestore', 05:18:07 INFO - 'sessionrestore_no_auto_restore', 05:18:07 INFO - 'tabpaint']}, 05:18:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:18:07 INFO - 'ts_paint', 05:18:07 INFO - 'tpaint', 05:18:07 INFO - 'sessionrestore', 05:18:07 INFO - 'sessionrestore_no_auto_restore', 05:18:07 INFO - 'tabpaint']}, 05:18:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['a11yr', 05:18:07 INFO - 'ts_paint', 05:18:07 INFO - 'tpaint', 05:18:07 INFO - 'sessionrestore', 05:18:07 INFO - 'sessionrestore_no_auto_restore', 05:18:07 INFO - 'tabpaint']}, 05:18:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['a11yr', 05:18:07 INFO - 'ts_paint', 05:18:07 INFO - 'tpaint', 05:18:07 INFO - 'sessionrestore', 05:18:07 INFO - 'sessionrestore_no_auto_restore', 05:18:07 INFO - 'tabpaint']}, 05:18:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['tsvgx', 05:18:07 INFO - 'tsvgr_opacity', 05:18:07 INFO - 'tart', 05:18:07 INFO - 'tscrollx', 05:18:07 INFO - 'cart']}, 05:18:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:18:07 INFO - 'tsvgr_opacity', 05:18:07 INFO - 'tart', 05:18:07 INFO - 'tscrollx', 05:18:07 INFO - 'cart']}, 05:18:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:07 INFO - 'talos_options': ['--disable-e10s'], 05:18:07 INFO - 'tests': ['tp5o']}, 05:18:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:07 INFO - 'tests': ['tp5o']}, 05:18:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:07 INFO - 'talos_options': ['--disable-e10s', 05:18:07 INFO - '--xperf_path', 05:18:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:07 INFO - 'tests': ['tp5n']}, 05:18:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:07 INFO - 'talos_options': ['--xperf_path', 05:18:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:07 INFO - 'tests': ['tp5n']}}, 05:18:07 INFO - 'talos.zip': {'path': '', 05:18:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:07 INFO - Running post-action listener: _resource_record_post_action 05:18:07 INFO - ##### 05:18:07 INFO - ##### Finished populate-webroot step (success) 05:18:07 INFO - ##### 05:18:07 INFO - ##### 05:18:07 INFO - ##### Running create-virtualenv step. 05:18:07 INFO - ##### 05:18:07 INFO - Running pre-action listener: _resource_record_pre_action 05:18:07 INFO - Running main action method: create_virtualenv 05:18:07 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:07 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 05:18:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:08 INFO - Using real prefix '/tools/python27' 05:18:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:08 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:11 INFO - Installing pip.................done. 05:18:11 INFO - Return code: 0 05:18:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:18:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:11 INFO - 'HOME': '/Users/cltbld', 05:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:11 INFO - 'LOGNAME': 'cltbld', 05:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:11 INFO - 'MOZ_NO_REMOTE': '1', 05:18:11 INFO - 'NO_EM_RESTART': '1', 05:18:11 INFO - 'PAGER': '/bin/cat', 05:18:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:11 INFO - 'PWD': '/builds/slave/test', 05:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:11 INFO - 'SHELL': '/bin/bash', 05:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:11 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:11 INFO - 'USER': 'cltbld', 05:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:11 INFO - 'XPC_FLAGS': '0x0', 05:18:11 INFO - 'XPC_SERVICE_NAME': '0', 05:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:11 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 05:18:11 INFO - Cleaning up... 05:18:12 INFO - Return code: 0 05:18:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:18:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:12 INFO - 'HOME': '/Users/cltbld', 05:18:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:12 INFO - 'LOGNAME': 'cltbld', 05:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:12 INFO - 'MOZ_NO_REMOTE': '1', 05:18:12 INFO - 'NO_EM_RESTART': '1', 05:18:12 INFO - 'PAGER': '/bin/cat', 05:18:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:12 INFO - 'PWD': '/builds/slave/test', 05:18:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:12 INFO - 'SHELL': '/bin/bash', 05:18:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:12 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:12 INFO - 'USER': 'cltbld', 05:18:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:12 INFO - 'XPC_FLAGS': '0x0', 05:18:12 INFO - 'XPC_SERVICE_NAME': '0', 05:18:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:12 INFO - Downloading/unpacking psutil>=3.1.1 05:18:12 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 05:18:12 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 05:18:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:12 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 05:18:12 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 05:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:14 INFO - Installing collected packages: psutil 05:18:14 INFO - Running setup.py install for psutil 05:18:14 INFO - building 'psutil._psutil_osx' extension 05:18:14 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 05:18:15 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 05:18:15 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 05:18:15 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 05:18:15 INFO - building 'psutil._psutil_posix' extension 05:18:15 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 05:18:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:15 INFO - ^ 05:18:15 INFO - 1 warning generated. 05:18:15 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 05:18:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:16 INFO - Successfully installed psutil 05:18:16 INFO - Cleaning up... 05:18:16 INFO - Return code: 0 05:18:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:18:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:16 INFO - 'HOME': '/Users/cltbld', 05:18:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:16 INFO - 'LOGNAME': 'cltbld', 05:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:16 INFO - 'MOZ_NO_REMOTE': '1', 05:18:16 INFO - 'NO_EM_RESTART': '1', 05:18:16 INFO - 'PAGER': '/bin/cat', 05:18:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:16 INFO - 'PWD': '/builds/slave/test', 05:18:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:16 INFO - 'SHELL': '/bin/bash', 05:18:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:16 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:16 INFO - 'USER': 'cltbld', 05:18:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:16 INFO - 'XPC_FLAGS': '0x0', 05:18:16 INFO - 'XPC_SERVICE_NAME': '0', 05:18:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:18:16 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 05:18:16 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 05:18:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:16 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 05:18:16 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 05:18:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:18:18 INFO - Installing collected packages: mozsystemmonitor 05:18:18 INFO - Running setup.py install for mozsystemmonitor 05:18:19 INFO - Successfully installed mozsystemmonitor 05:18:19 INFO - Cleaning up... 05:18:19 INFO - Return code: 0 05:18:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:18:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:19 INFO - 'HOME': '/Users/cltbld', 05:18:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:19 INFO - 'LOGNAME': 'cltbld', 05:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:19 INFO - 'MOZ_NO_REMOTE': '1', 05:18:19 INFO - 'NO_EM_RESTART': '1', 05:18:19 INFO - 'PAGER': '/bin/cat', 05:18:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:19 INFO - 'PWD': '/builds/slave/test', 05:18:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:19 INFO - 'SHELL': '/bin/bash', 05:18:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:19 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:19 INFO - 'USER': 'cltbld', 05:18:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:19 INFO - 'XPC_FLAGS': '0x0', 05:18:19 INFO - 'XPC_SERVICE_NAME': '0', 05:18:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:19 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:19 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 05:18:19 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 05:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:19 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 05:18:19 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 05:18:21 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:21 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 05:18:21 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 05:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:21 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 05:18:21 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 05:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18:22 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 05:18:22 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 05:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:22 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 05:18:22 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 05:18:22 INFO - Downloading docopt-0.6.1.tar.gz 05:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:23 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:23 INFO - Running setup.py install for blobuploader 05:18:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:23 INFO - Running setup.py install for requests 05:18:23 INFO - Running setup.py install for docopt 05:18:23 INFO - Successfully installed blobuploader requests docopt 05:18:23 INFO - Cleaning up... 05:18:23 INFO - Return code: 0 05:18:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:18:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:23 INFO - 'HOME': '/Users/cltbld', 05:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:23 INFO - 'LOGNAME': 'cltbld', 05:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:23 INFO - 'MOZ_NO_REMOTE': '1', 05:18:23 INFO - 'NO_EM_RESTART': '1', 05:18:23 INFO - 'PAGER': '/bin/cat', 05:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:23 INFO - 'PWD': '/builds/slave/test', 05:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:23 INFO - 'SHELL': '/bin/bash', 05:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:23 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:23 INFO - 'USER': 'cltbld', 05:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:23 INFO - 'XPC_FLAGS': '0x0', 05:18:23 INFO - 'XPC_SERVICE_NAME': '0', 05:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:24 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ySxkDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:24 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-FiO2wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:24 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ZCNcep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:24 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-San5ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:24 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-9GEApA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:24 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-RY2DTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:24 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-9U_How-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:24 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-obd476-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-PCmP8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-NFQhSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-NxICDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-wznNrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-haRWUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-7Jaj7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-uUmT6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-5SHA0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:25 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-QxufTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:25 INFO - Running setup.py install for manifestparser 05:18:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:26 INFO - Running setup.py install for mozcrash 05:18:26 INFO - Running setup.py install for mozdebug 05:18:26 INFO - Running setup.py install for mozdevice 05:18:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:18:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:26 INFO - Running setup.py install for mozfile 05:18:26 INFO - Running setup.py install for mozhttpd 05:18:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:26 INFO - Running setup.py install for mozinfo 05:18:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Running setup.py install for mozInstall 05:18:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Running setup.py install for mozleak 05:18:27 INFO - Running setup.py install for mozlog 05:18:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Running setup.py install for moznetwork 05:18:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Running setup.py install for mozprocess 05:18:27 INFO - Running setup.py install for mozprofile 05:18:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:27 INFO - Running setup.py install for mozrunner 05:18:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:28 INFO - Running setup.py install for mozscreenshot 05:18:28 INFO - Running setup.py install for moztest 05:18:28 INFO - Running setup.py install for mozversion 05:18:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:28 INFO - Cleaning up... 05:18:28 INFO - Return code: 0 05:18:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:28 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:28 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:18:28 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:28 INFO - 'HOME': '/Users/cltbld', 05:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:28 INFO - 'LOGNAME': 'cltbld', 05:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:28 INFO - 'MOZ_NO_REMOTE': '1', 05:18:28 INFO - 'NO_EM_RESTART': '1', 05:18:28 INFO - 'PAGER': '/bin/cat', 05:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:28 INFO - 'PWD': '/builds/slave/test', 05:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:28 INFO - 'SHELL': '/bin/bash', 05:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:28 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:28 INFO - 'USER': 'cltbld', 05:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:28 INFO - 'XPC_FLAGS': '0x0', 05:18:28 INFO - 'XPC_SERVICE_NAME': '0', 05:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-hmRIvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:28 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)) 05:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:28 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-yeOwT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-xeKuAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:29 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-Ucez6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-LHd1gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:29 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-3Zeu6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:29 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-Nu95kI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:29 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ce4XhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:29 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-2wlkgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:29 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-KrR1Ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:29 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-sr_cmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:30 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)) 05:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-8q15Bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 05:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-x73DEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:30 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)) 05:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-8w4jO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 05:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-pUJEyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:30 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)) 05:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-oKmwmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 05:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:30 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ia8_k7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:30 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)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:30 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)) 05:18:30 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:30 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:33 INFO - Downloading blessings-1.6.tar.gz 05:18:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:18:33 INFO - Installing collected packages: blessings 05:18:33 INFO - Running setup.py install for blessings 05:18:33 INFO - Successfully installed blessings 05:18:33 INFO - Cleaning up... 05:18:33 INFO - Return code: 0 05:18:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:18:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:33 INFO - Reading from file tmpfile_stdout 05:18:33 INFO - Current package versions: 05:18:33 INFO - blessings == 1.6 05:18:33 INFO - blobuploader == 1.2.4 05:18:33 INFO - docopt == 0.6.1 05:18:33 INFO - manifestparser == 1.1 05:18:33 INFO - mozInstall == 1.12 05:18:33 INFO - mozcrash == 0.17 05:18:33 INFO - mozdebug == 0.1 05:18:33 INFO - mozdevice == 0.48 05:18:33 INFO - mozfile == 1.2 05:18:33 INFO - mozhttpd == 0.7 05:18:33 INFO - mozinfo == 0.9 05:18:33 INFO - mozleak == 0.1 05:18:33 INFO - mozlog == 3.2 05:18:33 INFO - moznetwork == 0.27 05:18:33 INFO - mozprocess == 0.22 05:18:33 INFO - mozprofile == 0.28 05:18:33 INFO - mozrunner == 6.11 05:18:33 INFO - mozscreenshot == 0.1 05:18:33 INFO - mozsystemmonitor == 0.3 05:18:33 INFO - moztest == 0.7 05:18:33 INFO - mozversion == 1.4 05:18:33 INFO - psutil == 3.1.1 05:18:33 INFO - requests == 1.2.3 05:18:33 INFO - wsgiref == 0.1.2 05:18:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:18:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:33 INFO - 'HOME': '/Users/cltbld', 05:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:33 INFO - 'LOGNAME': 'cltbld', 05:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:33 INFO - 'MOZ_NO_REMOTE': '1', 05:18:33 INFO - 'NO_EM_RESTART': '1', 05:18:33 INFO - 'PAGER': '/bin/cat', 05:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:33 INFO - 'PWD': '/builds/slave/test', 05:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:33 INFO - 'SHELL': '/bin/bash', 05:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:33 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:33 INFO - 'USER': 'cltbld', 05:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:33 INFO - 'XPC_FLAGS': '0x0', 05:18:33 INFO - 'XPC_SERVICE_NAME': '0', 05:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 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)) 05:18:34 INFO - Cleaning up... 05:18:34 INFO - Return code: 0 05:18:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:34 INFO - 'HOME': '/Users/cltbld', 05:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:34 INFO - 'LOGNAME': 'cltbld', 05:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:34 INFO - 'MOZ_NO_REMOTE': '1', 05:18:34 INFO - 'NO_EM_RESTART': '1', 05:18:34 INFO - 'PAGER': '/bin/cat', 05:18:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:34 INFO - 'PWD': '/builds/slave/test', 05:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:34 INFO - 'SHELL': '/bin/bash', 05:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:34 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:34 INFO - 'USER': 'cltbld', 05:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:34 INFO - 'XPC_FLAGS': '0x0', 05:18:34 INFO - 'XPC_SERVICE_NAME': '0', 05:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:34 INFO - Downloading/unpacking jsonschema 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:36 INFO - Downloading/unpacking functools32 (from jsonschema) 05:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:37 INFO - warning: no files found matching '*.txt' 05:18:37 INFO - no previously-included directories found matching 'build' 05:18:37 INFO - no previously-included directories found matching 'dist' 05:18:37 INFO - no previously-included directories found matching '.git*' 05:18:37 INFO - Installing collected packages: jsonschema, functools32 05:18:37 INFO - Running setup.py install for functools32 05:18:37 INFO - warning: no files found matching '*.txt' 05:18:37 INFO - no previously-included directories found matching 'build' 05:18:37 INFO - no previously-included directories found matching 'dist' 05:18:37 INFO - no previously-included directories found matching '.git*' 05:18:37 INFO - Successfully installed jsonschema functools32 05:18:37 INFO - Cleaning up... 05:18:37 INFO - Return code: 0 05:18:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102c9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ca3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cb9571040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, '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.D62J5PLVMV/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.SwrjMJpjzk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 05:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:37 INFO - 'HOME': '/Users/cltbld', 05:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:37 INFO - 'LOGNAME': 'cltbld', 05:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:37 INFO - 'MOZ_NO_REMOTE': '1', 05:18:37 INFO - 'NO_EM_RESTART': '1', 05:18:37 INFO - 'PAGER': '/bin/cat', 05:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:37 INFO - 'PWD': '/builds/slave/test', 05:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:37 INFO - 'SHELL': '/bin/bash', 05:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:18:37 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:37 INFO - 'USER': 'cltbld', 05:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:37 INFO - 'XPC_FLAGS': '0x0', 05:18:37 INFO - 'XPC_SERVICE_NAME': '0', 05:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:37 INFO - Downloading/unpacking flake8 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:18:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:18:40 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 05:18:40 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 05:18:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:40 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 05:18:40 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 05:18:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:18:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:18:40 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 05:18:40 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 05:18:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:40 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 05:18:40 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 05:18:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:18:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:41 INFO - no previously-included directories found matching 'docs/_build' 05:18:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:18:41 INFO - Running setup.py install for pep8 05:18:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:41 INFO - no previously-included directories found matching 'docs/_build' 05:18:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:18:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:18:41 INFO - Cleaning up... 05:18:41 INFO - Return code: 0 05:18:41 INFO - Running post-action listener: _resource_record_post_action 05:18:41 INFO - Running post-action listener: _start_resource_monitoring 05:18:41 INFO - Starting resource monitoring. 05:18:41 INFO - ##### 05:18:41 INFO - ##### Finished create-virtualenv step (success) 05:18:41 INFO - ##### 05:18:41 INFO - ##### 05:18:41 INFO - ##### Running install step. 05:18:41 INFO - ##### 05:18:41 INFO - Running pre-action listener: _resource_record_pre_action 05:18:41 INFO - Running main action method: install 05:18:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:42 INFO - Reading from file tmpfile_stdout 05:18:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:18:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:18:42 INFO - Reading from file tmpfile_stdout 05:18:42 INFO - Output received: 05:18:42 INFO - Usage: mozinstall [options] installer 05:18:42 INFO - Options: 05:18:42 INFO - -h, --help show this help message and exit 05:18:42 INFO - -d DEST, --destination=DEST 05:18:42 INFO - Directory to install application into. [default: 05:18:42 INFO - "/builds/slave/test"] 05:18:42 INFO - --app=APP Application being installed. [default: firefox] 05:18:42 INFO - mkdir: /builds/slave/test/build/application 05:18:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:19:15 INFO - Reading from file tmpfile_stdout 05:19:15 INFO - Output received: 05:19:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:19:15 INFO - Running post-action listener: _resource_record_post_action 05:19:15 INFO - ##### 05:19:15 INFO - ##### Finished install step (success) 05:19:15 INFO - ##### 05:19:15 INFO - ##### 05:19:15 INFO - ##### Running run-tests step. 05:19:15 INFO - ##### 05:19:15 INFO - Running pre-action listener: _resource_record_pre_action 05:19:15 INFO - Running main action method: run_tests 05:19:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:15 INFO - 2016-05-24 05:19:15.722 screenresolution[1641:7611] starting screenresolution argv=screenresolution get 05:19:15 INFO - 2016-05-24 05:19:15.725 screenresolution[1641:7611] Display 0: 1600x1200x32@60 05:19:15 INFO - 2016-05-24 05:19:15.731 screenresolution[1642:7613] starting screenresolution argv=screenresolution list 05:19:15 INFO - Available Modes on Display 0 05:19:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:15 INFO - Intel Iris: 05:19:15 INFO - Chipset Model: Intel Iris 05:19:15 INFO - Type: GPU 05:19:15 INFO - Bus: Built-In 05:19:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:15 INFO - Vendor: Intel (0x8086) 05:19:15 INFO - Device ID: 0x0a2e 05:19:15 INFO - Revision ID: 0x0009 05:19:15 INFO - Displays: 05:19:15 INFO - Display: 05:19:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:15 INFO - Display Serial Number: 1600x1200 60 05:19:15 INFO - Main Display: Yes 05:19:15 INFO - Mirror: Off 05:19:15 INFO - Online: Yes 05:19:15 INFO - Rotation: Supported 05:19:15 INFO - Return code: 0 05:19:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:15 INFO - Python 2.7.3 05:19:15 INFO - Return code: 0 05:19:15 INFO - grabbing minidump binary from tooltool 05:19:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:15 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 0x102b41d30>, '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 0x102965db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b38b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:15 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 05:19:15 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 05:19:15 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 05:19:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:16 INFO - Return code: 0 05:19:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:19:17 INFO - Return code: 0 05:19:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0118 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D62J5PLVMV/Render', 05:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:17 INFO - 'HOME': '/Users/cltbld', 05:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:17 INFO - 'LOGNAME': 'cltbld', 05:19:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:17 INFO - 'MOZ_NO_REMOTE': '1', 05:19:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:17 INFO - 'NO_EM_RESTART': '1', 05:19:17 INFO - 'PAGER': '/bin/cat', 05:19:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:17 INFO - 'PWD': '/builds/slave/test', 05:19:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:17 INFO - 'SHELL': '/bin/bash', 05:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SwrjMJpjzk/Listeners', 05:19:17 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 05:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:17 INFO - 'USER': 'cltbld', 05:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:17 INFO - 'XPC_FLAGS': '0x0', 05:19:17 INFO - 'XPC_SERVICE_NAME': '0', 05:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:19:17 INFO - mozversion application_buildid: 20160524023517 05:19:17 INFO - mozversion application_changeset: 25a5ae2711f38f202ab57b20518d3554072f2f47 05:19:17 INFO - mozversion application_display_name: Nightly 05:19:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:17 INFO - mozversion application_name: Firefox 05:19:17 INFO - mozversion application_remotingname: firefox 05:19:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:19:17 INFO - mozversion application_vendor: Mozilla 05:19:17 INFO - mozversion application_version: 49.0a1 05:19:17 INFO - mozversion platform_buildid: 20160524023517 05:19:17 INFO - mozversion platform_changeset: 25a5ae2711f38f202ab57b20518d3554072f2f47 05:19:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:19:17 INFO - mozversion platform_version: 49.0a1 05:19:17 INFO - using testdate: 1464092357 05:19:17 INFO - actual date: 1464092357 05:19:17 INFO - starting webserver on 'localhost:49272' 05:19:17 INFO - SUITE-START | Running 2 tests 05:19:17 INFO - TEST-START | dromaeo_css 05:19:17 INFO - operating with platform_type : mac_ 05:19:17 INFO - Initialising browser for dromaeo_css test... 05:19:17 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmplSAT1a/profile http://localhost:49272/getInfo.html) 05:19:19 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 05:19:19 INFO - PROCESS | 1660 | colorDepth:24 05:19:19 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 05:19:19 INFO - PROCESS | 1660 | __metrics 05:19:19 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:19:19 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:19:20 INFO - TEST-INFO | 1660: exit 0 05:19:20 INFO - Browser initialized. 05:19:20 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:20 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmplSAT1a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:32 INFO - PROCESS | 1662 | RSS: Main: 238866432 05:19:32 INFO - PROCESS | 1662 | 05:20:24 INFO - PROCESS | 1662 | 1464092424040 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:22:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 05:22:34 INFO - PROCESS | 1662 | RSS: Main: 342507520 05:22:34 INFO - PROCESS | 1662 | 05:25:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 05:25:38 INFO - PROCESS | 1662 | RSS: Main: 366010368 05:25:38 INFO - PROCESS | 1662 | 05:28:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 05:28:40 INFO - PROCESS | 1662 | RSS: Main: 349093888 05:28:40 INFO - PROCESS | 1662 | 05:31:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 05:31:43 INFO - PROCESS | 1662 | RSS: Main: 366809088 05:31:43 INFO - PROCESS | 1662 | 05:34:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 05:34:47 INFO - PROCESS | 1662 | RSS: Main: 337371136 05:34:47 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 05:37:50 INFO - PROCESS | 1662 | RSS: Main: 375160832 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | __start_tp_report 05:37:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:37:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:37:50 INFO - PROCESS | 1662 | |i|pagename|runs| 05:37:50 INFO - PROCESS | 1662 | |0;dojo.html;3468.9479657805136;3476.6001909780384;3514.033640748792;3532.434810430331;3558.341706784245;5680.460356266148;5683.687397193158;5688.033034384164;5756.9424305756875;5757.510611598014;5691.345495268046;5748.511376532989;5757.856053598662;5765.1928729977835;5766.106253530709;4393.604575588196;5215.791368345262;5234.005538947587;5245.4754524547625;5265.420803711589;4480.619147372461;5251.264822924793;5278.788848446076;5281.9207711884355;5369.865753356158;5312.309399778023;5362.865928351803;5373.382061062969;5379.273798037274;5379.6503227290295;64.4677966792714;5251.159814429673;5271.841844744664;5298.178782288732;5366.8926621467745;5214.5306922376785;5239.1355426354985;5242.5281724644965;5277.577793776489;5280.049591073605;5526.944730552683;5581.74882130301;5656.066748986413;5670.0360938640115;5717.571182161355;6106.511479081707;6108.633481991094;6219.160413344186;6219.253689557282;6230.065490176465;5681.23303354046;5925.4370834770625;6129.79389316787;6199.845003874894;6211.409916057967;5920.911186332209;5942.61373010753;5944.286685597756;5947.078202878604;5951.434613711691;6139.553468857682;6180.598261113103;6249.843753906189;6274.152989346379;6279.403456671609;4852.611791056707;6108.755649774048;6124.930239974474;6185.443310102112;6205.069239614094;4684.344191813149;5999.4900433463645;6242.719077641517;6267.310595834455;6286.874262514724;5328.946710532845;6054.182685337511;6118.663473508911;6185.288691800411;6255.718492667841;5077.492250774893;6205.441510264098;6219.346968568326;6226.159468471698;6237.625742455467;4958.528939750718;4961.70229786214;4964.67729597575;4970.204767237225;5003.399592048978;4443.089166720879;4447.421835161408;4466.888327791767;4471.575200355961;4479.193745125908;5601.159826026129;5668;5674.716264186856;5727.742251598688;5739.626924249994;6208.906866397007;6326.620402775849;6327.746890124344;6329.556931014877;6330.873382532323;5617.606767526234;5701.71491425427;5750.827475175752;5752.971235143881;5821.825345239649;5390.54180394663;5430.647007944471;5432.348118225838;5440.347158341025;5455.863603409946;6247.718852651551;6358.459530939922;6361.077643741632;6382.904256436064;6386.936130638727;5692.686902220417;6146.078088286793;6161.014237722122;6323.241211054519;6326.082718005955;6227.408396202353;6281.811545653638;6286;6302.401271879165;6310.305866354789;6096.695165241631;6134.907976380268;6155.261368635793;6155.938440615537;6262.561620686508;5999.730012149551;6038.456538911519;6101.186783078283;6215.285242197025;6238.75044998195;5838.503727183067;5838.5621078418435;5843.2695913010875;5845.707714614337;5895.174675545341;5651.943480565306;5799.101139323411;5808.477237048686;5813.941860581509;5874.706264686663;5739.799107031234;5795.246617939641;5917.201177840936;5954.3152537458745;5979.1928089707335;6122.571420000557;6135.754569817157;6140.785072522441;6143.539234557516;6151.738893526726;5973.701314934323;5982.641041537521;6005.489533389711;6021;6022.09668549721 05:37:50 INFO - PROCESS | 1662 | |1;ext.html;470.4425256071555;508.2604810001446;508.63886640485254;516.9379674439068;520.7838746920028;30357.39285214297;30629.38741225174;32577.696892124317;42752.78623606878;43912.780436097775;45204.19183232675;45659.63021109364;45786.542134578645;45884;46044.76977615107;9492.955774864777;9679.806403871919;9697.757556061084;9698.369605975624;9719.028097190296;8583.442076265053;8809.295256379506;9238.491882946435;11595.362255075968;11821.70445738859;11671.941640291787;11744.706382340468;11747.001504872096;11751.7649647007;11784.175107742461;8568.614412351397;11595.072394208442;11635.185537012412;11640.243384180087;11646.708832279219;11268.774624507463;11598.246114002563;11609.24539904912;11783.646490605295;11806.822897656542;36332;40938.38592421086;41454.37818432726;41884.79057604723;42029.57970420287;3182.315802102536;3217.4530329843747;3236.2071292533246;3335.399628066947;3514.595821480537;28999.275018124717;29116.27209319763;29697.554536681968;29739.553906691417;29759.40481190364;2700.4599080184043;2727.1273192578383;2744.725527447259;2764.3365592257915;2797.608334833125;392984;393921.0607893913;402837;403209;403730;63594.0460893087;63950.0407493888;63983;64139;64149.67925160344;35908;36521;36635.26729465396;36740;67047.32952670411;16057.277422516016;16059.919700401424;16151.676966460605;16174;16195.352185912667;11672.299662020307;11904.107191960638;12865.005924081783;12944.029197810014;12989.480420783251;16483.34066637345;19733.210671572975;19807.405777826687;20005.899970500053;20733.27433539849;7740.8451830964195;7790.415718821111;7890.921090789018;7940.960295198487;7977.441579089408;841.6086519768337;896.4666023715879;980.9117179453883;1017.1405162637707;1032.2361452525076;29679;29979.250518737204;30067.646955887038;30601.846990764905;30610.54084188739;974.70758772368;1045.6706137566794;1050.7863417752508;1085.7557049663762;1101.3336931156534;15043.323050462755;15364.923175384052;15424.845751542565;15609.765853512206;15740.763888541682;661780;669790;672256;676989;680065;61252.38747612556;61294.38705612887;63154.368456315766;63190.73618527695;63242.36757632457;1304.6999190186311;1321.6497628128554;1422.8434872164053;1424.2166808255624;1461.503088949741;25777.226683199533;29555.261118472212;30730.231744206572;31103.377932441676;31201;51301.74349128231;51449;51789.22316165336;52016;52089;521820;523428;524573;525114;525276;23873.04507819668;25390;26312.473750525267;26660.8666956664;27685.169444916686;47814.760926195144;47869.5213047879;47960.7601961988;48363.75818120887;48413.5158648409;37998;38162.960595634475;38485.03787405337;38803.80598096991;39533.2093358137;37055.444168338036;37460.625393745715;38007.619923800405;38061.238775223785;38458.42312365261;37976.81011594924;38177.2364552713;38177.66378176751;39172.02069948274;40056.998575035854;33010.009699709044;34045;34140;34446.48330275098;34522.65477345194;20556.69164962497;23885.044598215878;24927.875360623082;25082.37294067663;25200.865961032163 05:37:50 INFO - PROCESS | 1662 | |2;jquery.html;329.55674617639283;337.9476181191913;379.6696873719863;388.1886856469979;402.39640539191214;16368.017918924872;20194;21151.048202830872;21216.4695882603;22026;17599.82400175998;21108.788912110875;21311.57376852466;21919;22072.33782986513;5775.393583673716;5894.440028197314;6093.329733729286;6117.480014198798;6143.539234557404;5943.435373639497;6034.24571928509;6081.935661259284;6116.08258761185;6129.969350153243;5331.786728530854;6061.727222274986;6067.089936509515;6082.5742198046155;6087.695615219243;5858.970705146447;6003.339632640406;6055.5761096723245;6066.969665151646;6077.5137988961;5180.5337519623035;5182.714950677711;5192.506711862367;5261.421243663194;5269.947300527022;24990.5001899961;25313.240602781945;25430.98276068955;25457;25918.48163036729;2165.436986383552;2243.4054975431523;2280.942976425586;2285.611446054175;2396.341006223285;12005.819912701316;14905.180215088165;14978.62553436162;15098.094114353064;15530.9223453882;1730.4030109612163;1783.7681101456858;1829.0945981738992;1829.9634007319912;1873.4473330367439;76610;101626.9837301625;113331;120130;125307;9510.858696956271;10527.157827373792;10653.520591573242;10657.094146997591;10757.085647720032;13969.860301396857;14023.579292621238;14035.087719298213;14038.227897465737;14130.50543230982;8375.49747015169;8600.612972416257;8680.696175633822;8748.300135989106;8835.160659737314;8690.522021288763;8716.477011379211;8720.032385264689;8847.46915185091;8874.29005679544;6237.407446292595;6279.215098112736;6674.399304062561;6833.241717055599;6845.760398386132;1538.2462593329412;1616.585487698264;1771.6269890834449;1843.7510936023734;1878.445858471754;811.4319976016811;848.9642635984213;920.7283851263893;990.6829814459304;1029.45953374479;17135;20243.797562024487;20663.070161842752;20698.79301206999;20716.792832071787;955.3408148377597;983.2822039910905;989.1987490133015;1000.8548760429696;1028.7788125552893;10999.780004399867;11272.380019099028;11292.830607540893;11317.434128293553;11339.433028348549;519589.2061619079;570139;581418;607540.9622951857;608010;119353.40323298328;120658.79341206652;121664;122441.38779306046;126079.73920260863;1012.3824467075078;1032.0659802878406;1051.75283808305;1055.6569115037644;1060.7242117049623;16461.423850165185;18188.818111818713;18372.897626142476;18691.90654046721;18778.718319225496;47936.28095578499;48781.51218487912;50002.74993125055;50174;50251.24623130583;71444.64277678578;71744;71993;73121;73501.6324918372;7625.161232264336;7786.649600767866;7918.841623167619;8016.599170041358;8223.79440513968;9744.902550974399;11358.602448914578;12260.26438413698;12452.501899923902;12614.306213158365;9622.51887405613;9780.924098349318;10417.42704151279;10645.308054976402;10725.517351719347;7961.601919903866;10289.176865850564;10453.634122805664;10556.366618002945;10652.733681658021;9481.952590237004;10529.683789526309;10834.620788272372;10861.402622855503;10985.61550345734;7953.204679532232;7979.9202007979175;8121.228483294078;8220.219079187467;8229.341652667652;9036.689679996494;9340.813183736424;9940.652077177265;10690;10708.143348532254 05:37:50 INFO - PROCESS | 1662 | |3;mootools.html;1382.0463879922859;1554.5025591810622;1571.55210764932;1803.9549011274717;1946.04643724575;7937.642806073726;8524.147585241473;8815.911840881588;8820.117988201193;8824.69113581024;8385.245327920486;8703.390762646617;8706.303495720344;8816.867746983784;8825.02924678285;2297.322289924468;2335.964960525585;2341.648752687102;2350.529894021194;2354.4702441950594;2312.283192210412;2349.823763217757;2358.3278765551822;2363.267387109993;2368.0527788884497;1935.9709604355946;2306.5963456395066;2314.3404129823;2322;2331.428799944016;2308.2613563659634;2314.5314964351765;2351.8588884666806;2354.19957214547;2356.7761062699014;2346.9647955280684;2360.3155085025323;2377.940551486209;2381.2380038387723;2386.140989243871;8471.788205294917;8756.781080472976;8767.605457754416;8773.42972706772;8791.208791208757;5319.175527793198;5429.049916264638;5483.341998960111;5487.094629386146;5496.780128794843;3232.3212125453683;3270.8528116234825;3273.9508907366408;3494.930101397958;3560.2701446203464;1011.5903059261011;1025.7333093395696;1028.1415018459595;1034.2088302448633;1035.9741006474824;9748.512574371252;11435.256708313933;11541.826872596917;11648.068154547616;11677.591284305094;4741.83403580873;4752.500987396343;4790.616750659939;4848.296996935424;4882.414110306715;10201.438920859424;10240;10262.179025677928;10264.076233139054;10293.433861137559;5394;5427.674339539641;5428.022955867903;5438.021156191923;5439.129739241703;4441.777911104432;4603.562661547147;4776.9761151194025;4798.2082956312515;4958.330625365529;3978.224246271949;3999.1801680655394;4068.3287257602165;4089.9182016359505;4188.476440444944;1270.1490001698885;1281.2568710148091;1286.9742605148033;1295.2617008305176;1304.3217526885967;637.7321524959528;639.3478651775164;864.3862857371337;914.5960949941807;941.0260380506144;4530.501644819067;5293.115252187567;5711.200431939532;5776.566757493205;5910.674912879746;765.4144579396766;768.8428914483666;919.2416256588208;925.1303774451993;978.5351957820121;3322.368749937504;3797.0697179191275;3823.770573765583;3859.286032084073;3923.2349691810396;10487.790244195074;10712.78574428507;10738.463076846283;10755.78488430227;10913.618023369145;10463.319884207502;10804;10809.567617295377;10932.016118549369;10945.945270273756;8232.547209903512;8289.005319361595;8304.6678132874;8372.706955256537;8396.580170991427;5669.291338582677;5679.886402271849;5695;5836.620619659709;5958.404159584007;9840.60637574489;10168.084872361524;10293.639722609672;10304.587816487257;10304.742381440525;10293.845592316271;10457.84313235287;10460.581576736846;10460.68617941463;10485.423301718478;7021.1574611047;7058.823529411806;7118.537295075804;7192.676329565287;7210.206877243394;8234.547099909563;8418.789530261793;8424.536650484282;8430.367722420966;8437.325013998743;6094.7562097517375;7279.417646588155;7465.4027677786735;7517.02278703765;7624.809379765328;7222.89165662526;7314.122305323182;7332.633368331669;7412.258774122544;7492.812679683051;6922.58464492132;6970.756023539152;7036.5074444790425;7038.8944165838575;7043.6830342633675;4630.560096790799;4713.646476514206;4729.929051064168;4788.3056956741775;4833.154198015263;4206.978965105155;4875.3174555562955;4918.778654960607;4968.254761785761;5043.52086551777 05:37:50 INFO - PROCESS | 1662 | |4;prototype.html;300.1296240901384;306.8603785277698;316.38147421790404;321.866425433445;321.86642543344516;5133.766811437027;5322.749153948819;5389.137737961922;5404.189371594258;5443.374011988622;4246.872593822182;4395;5293.6294459387855;5340.463279942026;5342.412334643186;3246.8376581170965;4136.4695062826795;4136.5036195656485;4137.91724165518;4153.273177193994;3223.7098661120494;3265.4611989021782;4142.958570414303;4148.771817550033;4164.500259968793;3523.083998160484;4103.712740108193;4104.296716862514;4107.876763697094;4129.7315674481215;3709.6830625128096;3785.1861849702173;4089.1821635672986;4095.2219078375015;4132.297509423405;3558.4128618777872;3572.8384327482017;3573.213892943548;3597.3704601694863;3603.828755654423;4172.7287726298;4998.775544882493;5084.466131056222;5129.615278854064;5204.34945631796;4543.250363689987;4573.931391029138;4580.198465268565;4593.311003349491;4595.126925884105;4647.37260469839;4686.461056978457;4908.361912951329;5304.38999515053;5506.449355064502;3427.0746898337557;3477.139730244307;3731.8320675569394;3782.5650050244026;3822.522184726909;5349.893002139936;5549.833504994816;5771.56713246504;5824.504917082053;5864.472197502203;3846.5576458707606;3879.7284190106984;3883.66988805949;3890.358090915024;3913.3738601824143;3337.766356355032;3510.9116173986145;3634.7823479134786;3899.9610003900166;3910.687145028391;3880.3791393377487;4554.567316104964;4584.679072464962;4597.586217240398;4598.655100867449;4756.738379389098;5084;5357.276767636397;5727.971360143172;5731.656100633975;1269.2574843716345;1308.672831792052;1314.737052589486;1337.9866201338057;1355.600097971101;737.9114506259283;768.6656304507557;785.4305628419351;797.9586639435477;816.3061397811931;3909.8240579173435;3935.4490371348033;4120.1553681495225;4405.669574781904;4419.779011049434;4503.324501324828;4722.480527142012;4838.0082083172865;4858.3441235432765;4859.198232291703;3735.122246272124;4042.4936539345795;4425.004374015885;4439.4228750262255;4507;2232.983992283507;2323.7211534615953;2329.718990619827;2557.923262302134;2576.561984462646;4307.698461107692;4458.238995596721;4671.906561868744;4739.715617062987;4932.950670493249;3976.6619837313574;4286.5070516890955;4592.668126243419;4888.653446345703;5026.572741317029;4572.199865023674;5055.8988820223385;5177.456367081402;6132.877342453126;6134.938650613437;5060.380678182946;5317;5442.836714898559;5889.5582831286965;5913.497352724895;5249.737513124405;5571.804986825442;5804.796832110856;5846.56150788684;5909.197694703157;5095.573214526517;5369.301990741179;5452.700101494456;5579.903153749708;6089.695515224133;3842.51968503937;3855.2482265958993;3891.6379267256234;3909.6285852843935;3917.177392747441;5078.5175408336145;5460.645058071213;5490.505854473116;5528.75120619565;5952.375000624872;4342.935980684626;5593.328800543961;5609.887802244014;5639.830805075854;5670.376258611467;5636.872625474839;5679.290088738908;5689.089745640677;5692.5445964321925;5745.913811292918;4698.875247960205;5396.3524377075;5566.359868615161;5613.494785469327;5617.578681598815;4786.760661966958;5035.647504674784;5212.895742085213;5232.790688372423;5640.576956728344;4586.770661466847;5596.356419011866;5604.663720176803;5605.775768969195;5615.747291371979 05:37:50 INFO - PROCESS | 1662 | |5;yui.html;665.8102440804371;723.52247516639;727.3272223193547;732.765515035189;736.4550183371359;14671.559853204395;14995.175265360414;15546.766798498018;15738.370465181379;15764.369425223005;14372;14477.493287734931;14532.491362802304;14536.491222807204;14546.418143274257;718.4001358865354;743.126825979472;890.7238755985633;910.2263076385086;911.3210658059754;885.3846576629245;914.2229105260509;915.9129882661136;919.5448253114718;920.8158368326328;962.4658314635375;969.050330675938;969.2827307792219;970.8252514547379;971.0774763974252;754.3964828137523;896.4173287363201;923.4736200365794;967.3180407812475;967.9884520675911;686.7939618114546;687.7249100359846;690.2303931116819;690.7478770248823;693.1370443797454;14506.129632221995;14791.40834366624;14815.48145814902;14823.851761482356;14829.555113346616;12129.636110916686;12163.209391389531;12280.263184208976;12372.690682732913;12412.689682757913;10789.56841726338;10853.945730271376;10875.238733288674;10880.836787448194;10964.835527467094;3938.9212215755524;3961.485006949107;3968.6825053995613;4085.9795701021603;4093.6111069448357;79683;80857.19142808614;81261;81667;81709.59145204295;9084.22784063362;9440.48077355752;9484.762880928032;9616.75958101053;9859.457729824786;20407.183712651327;21040.78959210419;21158;21266.25568105109;21305.573888522453;6006.189164462829;6909.412699920463;6940.826479337956;6956.930430695628;6964.895526567106;3986.6412022918075;4011.5787842276723;4033.1731994940965;4035.3946907963455;4042.2319759245647;3269.2807582331848;3367.2760356523463;3368.1579605098727;3368.2758206985613;3374.4600863861665;597.8266302772234;610.1457958857635;614.8247749391402;618.1593033474471;620.6648409858716;457.4761897627198;492.2588328701909;525.1335296760376;553.582045555611;556.3185098254687;6497.18785151856;7413.7405190819145;7574.280443357872;7714.807129821799;7841.843163136819;536.5010540197653;537.8198303568348;552.8009916430082;559.3288054334814;569.4760820045568;3664.3037822814113;3679.7056235501095;3693.6306369362846;3766.3408903442337;3787.62123787619;52234.73882630563;79398.6030069846;91153.54423227975;92202.53898730464;93888;55298.72350638221;80925.59537202277;81678.59160704158;82512;82897;68422.65788671025;68485.3151468489;68955.31044689589;69110.30889691038;70399.29600704029;7215.747448839265;7309.561426314438;7315.085614298213;7359.448041397024;7436.702531898663;44452.333215001156;45450.772746136056;45807.31289030734;45840.77079614581;46014.769926150155;55446;56914;57084.85830283454;57133.143002855824;58216;10550.261481696207;10884.836727449254;10943.343399396063;11070.63156377522;11254.94372528132;12010.819837702616;12074.45664945062;12356.567520137112;12776.10567260283;13153.802692959805;10409.68770936873;10669.626563070255;11520.82718759236;11652.067834573045;11889.940550297193;9983.400995939975;10024;10222.6422075227;10357.223208259562;12341.691457713629;10077.899221007696;10115.696529104138;10330.741731456774;10382.221333400177;10546.419946902994;8234.876476852973;8358.08061113265;8552;8684.956575217084;8771.473711577326;8503.829923401374;8537.402381833212;8591.914080859362;8605.526696031779;8617.181367770052 05:37:50 INFO - PROCESS | 1662 | __end_tp_report 05:37:50 INFO - PROCESS | 1662 | __start_cc_report 05:37:50 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1155 05:37:50 INFO - PROCESS | 1662 | __end_cc_report 05:37:50 INFO - PROCESS | 1662 | __startTimestamp1464093470325__endTimestamp 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:37:50 INFO - PROCESS | 1662 | Number of tests: 6 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5669.34 Median:5838.56 stddev:755.71 (12.9%) stddev-sans-first:738.05 05:37:50 INFO - PROCESS | 1662 | Values: 3468.9 3476.6 3514.0 3532.4 3558.3 5680.5 5683.7 5688.0 5756.9 5757.5 5691.3 5748.5 5757.9 5765.2 5766.1 4393.6 5215.8 5234.0 5245.5 5265.4 4480.6 5251.3 5278.8 5281.9 5369.9 5312.3 5362.9 5373.4 5379.3 5379.7 64.5 5251.2 5271.8 5298.2 5366.9 5214.5 5239.1 5242.5 5277.6 5280.0 5526.9 5581.7 5656.1 5670.0 5717.6 6106.5 6108.6 6219.2 6219.3 6230.1 5681.2 5925.4 6129.8 6199.8 6211.4 5920.9 5942.6 5944.3 5947.1 5951.4 6139.6 6180.6 6249.8 6274.2 6279.4 4852.6 6108.8 6124.9 6185.4 6205.1 4684.3 5999.5 6242.7 6267.3 6286.9 5328.9 6054.2 6118.7 6185.3 6255.7 5077.5 6205.4 6219.3 6226.2 6237.6 4958.5 4961.7 4964.7 4970.2 5003.4 4443.1 4447.4 4466.9 4471.6 4479.2 5601.2 5668.0 5674.7 5727.7 5739.6 6208.9 6326.6 6327.7 6329.6 6330.9 5617.6 5701.7 5750.8 5753.0 5821.8 5390.5 5430.6 5432.3 5440.3 5455.9 6247.7 6358.5 6361.1 6382.9 6386.9 5692.7 6146.1 6161.0 6323.2 6326.1 6227.4 6281.8 6286.0 6302.4 6310.3 6096.7 6134.9 6155.3 6155.9 6262.6 5999.7 6038.5 6101.2 6215.3 6238.8 5838.5 5838.6 5843.3 5845.7 5895.2 5651.9 5799.1 5808.5 5813.9 5874.7 5739.8 5795.2 5917.2 5954.3 5979.2 6122.6 6135.8 6140.8 6143.5 6151.7 5973.7 5982.6 6005.5 6021.0 6022.1 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:66951.27 Median:36120.00 stddev:145300.60 (402.3%) stddev-sans-first:145622.47 05:37:50 INFO - PROCESS | 1662 | Values: 470.4 508.3 508.6 516.9 520.8 30357.4 30629.4 32577.7 42752.8 43912.8 45204.2 45659.6 45786.5 45884.0 46044.8 9493.0 9679.8 9697.8 9698.4 9719.0 8583.4 8809.3 9238.5 11595.4 11821.7 11671.9 11744.7 11747.0 11751.8 11784.2 8568.6 11595.1 11635.2 11640.2 11646.7 11268.8 11598.2 11609.2 11783.6 11806.8 36332.0 40938.4 41454.4 41884.8 42029.6 3182.3 3217.5 3236.2 3335.4 3514.6 28999.3 29116.3 29697.6 29739.6 29759.4 2700.5 2727.1 2744.7 2764.3 2797.6 392984.0 393921.1 402837.0 403209.0 403730.0 63594.0 63950.0 63983.0 64139.0 64149.7 35908.0 36521.0 36635.3 36740.0 67047.3 16057.3 16059.9 16151.7 16174.0 16195.4 11672.3 11904.1 12865.0 12944.0 12989.5 16483.3 19733.2 19807.4 20005.9 20733.3 7740.8 7790.4 7890.9 7941.0 7977.4 841.6 896.5 980.9 1017.1 1032.2 29679.0 29979.3 30067.6 30601.8 30610.5 974.7 1045.7 1050.8 1085.8 1101.3 15043.3 15364.9 15424.8 15609.8 15740.8 661780.0 669790.0 672256.0 676989.0 680065.0 61252.4 61294.4 63154.4 63190.7 63242.4 1304.7 1321.6 1422.8 1424.2 1461.5 25777.2 29555.3 30730.2 31103.4 31201.0 51301.7 51449.0 51789.2 52016.0 52089.0 521820.0 523428.0 524573.0 525114.0 525276.0 23873.0 25390.0 26312.5 26660.9 27685.2 47814.8 47869.5 47960.8 48363.8 48413.5 37998.0 38163.0 38485.0 38803.8 39533.2 37055.4 37460.6 38007.6 38061.2 38458.4 37976.8 38177.2 38177.7 39172.0 40057.0 33010.0 34045.0 34140.0 34446.5 34522.7 20556.7 23885.0 24927.9 25082.4 25200.9 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33720.48 Median:25443.99 stddev:96268.22 (378.4%) stddev-sans-first:96505.63 05:37:50 INFO - PROCESS | 1662 | Values: 329.6 337.9 379.7 388.2 402.4 16368.0 20194.0 21151.0 21216.5 22026.0 17599.8 21108.8 21311.6 21919.0 22072.3 5775.4 5894.4 6093.3 6117.5 6143.5 5943.4 6034.2 6081.9 6116.1 6130.0 5331.8 6061.7 6067.1 6082.6 6087.7 5859.0 6003.3 6055.6 6067.0 6077.5 5180.5 5182.7 5192.5 5261.4 5269.9 24990.5 25313.2 25431.0 25457.0 25918.5 2165.4 2243.4 2280.9 2285.6 2396.3 12005.8 14905.2 14978.6 15098.1 15530.9 1730.4 1783.8 1829.1 1830.0 1873.4 76610.0 101627.0 113331.0 120130.0 125307.0 9510.9 10527.2 10653.5 10657.1 10757.1 13969.9 14023.6 14035.1 14038.2 14130.5 8375.5 8600.6 8680.7 8748.3 8835.2 8690.5 8716.5 8720.0 8847.5 8874.3 6237.4 6279.2 6674.4 6833.2 6845.8 1538.2 1616.6 1771.6 1843.8 1878.4 811.4 849.0 920.7 990.7 1029.5 17135.0 20243.8 20663.1 20698.8 20716.8 955.3 983.3 989.2 1000.9 1028.8 10999.8 11272.4 11292.8 11317.4 11339.4 519589.2 570139.0 581418.0 607541.0 608010.0 119353.4 120658.8 121664.0 122441.4 126079.7 1012.4 1032.1 1051.8 1055.7 1060.7 16461.4 18188.8 18372.9 18691.9 18778.7 47936.3 48781.5 50002.7 50174.0 50251.2 71444.6 71744.0 71993.0 73121.0 73501.6 7625.2 7786.6 7918.8 8016.6 8223.8 9744.9 11358.6 12260.3 12452.5 12614.3 9622.5 9780.9 10417.4 10645.3 10725.5 7961.6 10289.2 10453.6 10556.4 10652.7 9482.0 10529.7 10834.6 10861.4 10985.6 7953.2 7979.9 8121.2 8220.2 8229.3 9036.7 9340.8 9940.7 10690.0 10708.1 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5681.84 Median:4747.17 stddev:3228.04 (68.0%) stddev-sans-first:3220.92 05:37:50 INFO - PROCESS | 1662 | Values: 1382.0 1554.5 1571.6 1804.0 1946.0 7937.6 8524.1 8815.9 8820.1 8824.7 8385.2 8703.4 8706.3 8816.9 8825.0 2297.3 2336.0 2341.6 2350.5 2354.5 2312.3 2349.8 2358.3 2363.3 2368.1 1936.0 2306.6 2314.3 2322.0 2331.4 2308.3 2314.5 2351.9 2354.2 2356.8 2347.0 2360.3 2377.9 2381.2 2386.1 8471.8 8756.8 8767.6 8773.4 8791.2 5319.2 5429.0 5483.3 5487.1 5496.8 3232.3 3270.9 3274.0 3494.9 3560.3 1011.6 1025.7 1028.1 1034.2 1036.0 9748.5 11435.3 11541.8 11648.1 11677.6 4741.8 4752.5 4790.6 4848.3 4882.4 10201.4 10240.0 10262.2 10264.1 10293.4 5394.0 5427.7 5428.0 5438.0 5439.1 4441.8 4603.6 4777.0 4798.2 4958.3 3978.2 3999.2 4068.3 4089.9 4188.5 1270.1 1281.3 1287.0 1295.3 1304.3 637.7 639.3 864.4 914.6 941.0 4530.5 5293.1 5711.2 5776.6 5910.7 765.4 768.8 919.2 925.1 978.5 3322.4 3797.1 3823.8 3859.3 3923.2 10487.8 10712.8 10738.5 10755.8 10913.6 10463.3 10804.0 10809.6 10932.0 10945.9 8232.5 8289.0 8304.7 8372.7 8396.6 5669.3 5679.9 5695.0 5836.6 5958.4 9840.6 10168.1 10293.6 10304.6 10304.7 10293.8 10457.8 10460.6 10460.7 10485.4 7021.2 7058.8 7118.5 7192.7 7210.2 8234.5 8418.8 8424.5 8430.4 8437.3 6094.8 7279.4 7465.4 7517.0 7624.8 7222.9 7314.1 7332.6 7412.3 7492.8 6922.6 6970.8 7036.5 7038.9 7043.7 4630.6 4713.6 4729.9 4788.3 4833.2 4207.0 4875.3 4918.8 4968.3 5043.5 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4343.62 Median:4598.12 stddev:1353.50 (29.4%) stddev-sans-first:1322.83 05:37:50 INFO - PROCESS | 1662 | Values: 300.1 306.9 316.4 321.9 321.9 5133.8 5322.7 5389.1 5404.2 5443.4 4246.9 4395.0 5293.6 5340.5 5342.4 3246.8 4136.5 4136.5 4137.9 4153.3 3223.7 3265.5 4143.0 4148.8 4164.5 3523.1 4103.7 4104.3 4107.9 4129.7 3709.7 3785.2 4089.2 4095.2 4132.3 3558.4 3572.8 3573.2 3597.4 3603.8 4172.7 4998.8 5084.5 5129.6 5204.3 4543.3 4573.9 4580.2 4593.3 4595.1 4647.4 4686.5 4908.4 5304.4 5506.4 3427.1 3477.1 3731.8 3782.6 3822.5 5349.9 5549.8 5771.6 5824.5 5864.5 3846.6 3879.7 3883.7 3890.4 3913.4 3337.8 3510.9 3634.8 3900.0 3910.7 3880.4 4554.6 4584.7 4597.6 4598.7 4756.7 5084.0 5357.3 5728.0 5731.7 1269.3 1308.7 1314.7 1338.0 1355.6 737.9 768.7 785.4 798.0 816.3 3909.8 3935.4 4120.2 4405.7 4419.8 4503.3 4722.5 4838.0 4858.3 4859.2 3735.1 4042.5 4425.0 4439.4 4507.0 2233.0 2323.7 2329.7 2557.9 2576.6 4307.7 4458.2 4671.9 4739.7 4933.0 3976.7 4286.5 4592.7 4888.7 5026.6 4572.2 5055.9 5177.5 6132.9 6134.9 5060.4 5317.0 5442.8 5889.6 5913.5 5249.7 5571.8 5804.8 5846.6 5909.2 5095.6 5369.3 5452.7 5579.9 6089.7 3842.5 3855.2 3891.6 3909.6 3917.2 5078.5 5460.6 5490.5 5528.8 5952.4 4342.9 5593.3 5609.9 5639.8 5670.4 5636.9 5679.3 5689.1 5692.5 5745.9 4698.9 5396.4 5566.4 5613.5 5617.6 4786.8 5035.6 5212.9 5232.8 5640.6 4586.8 5596.4 5604.7 5605.8 5615.7 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17366.46 Median:564.40 stddev:24317.98 (4308.6%) stddev-sans-first:24353.86 05:37:50 INFO - PROCESS | 1662 | Values: 665.8 723.5 727.3 732.8 736.5 14671.6 14995.2 15546.8 15738.4 15764.4 14372.0 14477.5 14532.5 14536.5 14546.4 718.4 743.1 890.7 910.2 911.3 885.4 914.2 915.9 919.5 920.8 962.5 969.1 969.3 970.8 971.1 754.4 896.4 923.5 967.3 968.0 686.8 687.7 690.2 690.7 693.1 14506.1 14791.4 14815.5 14823.9 14829.6 12129.6 12163.2 12280.3 12372.7 12412.7 10789.6 10853.9 10875.2 10880.8 10964.8 3938.9 3961.5 3968.7 4086.0 4093.6 79683.0 80857.2 81261.0 81667.0 81709.6 9084.2 9440.5 9484.8 9616.8 9859.5 20407.2 21040.8 21158.0 21266.3 21305.6 6006.2 6909.4 6940.8 6956.9 6964.9 3986.6 4011.6 4033.2 4035.4 4042.2 3269.3 3367.3 3368.2 3368.3 3374.5 597.8 610.1 614.8 618.2 620.7 457.5 492.3 525.1 553.6 556.3 6497.2 7413.7 7574.3 7714.8 7841.8 536.5 537.8 552.8 559.3 569.5 3664.3 3679.7 3693.6 3766.3 3787.6 52234.7 79398.6 91153.5 92202.5 93888.0 55298.7 80925.6 81678.6 82512.0 82897.0 68422.7 68485.3 68955.3 69110.3 70399.3 7215.7 7309.6 7315.1 7359.4 7436.7 44452.3 45450.8 45807.3 45840.8 46014.8 55446.0 56914.0 57084.9 57133.1 58216.0 10550.3 10884.8 10943.3 11070.6 11254.9 12010.8 12074.5 12356.6 12776.1 13153.8 10409.7 10669.6 11520.8 11652.1 11889.9 9983.4 10024.0 10222.6 10357.2 12341.7 10077.9 10115.7 10330.7 10382.2 10546.4 8234.9 8358.1 8552.0 8685.0 8771.5 8503.8 8537.4 8591.9 8605.5 8617.2 05:37:50 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:37:50 INFO - PROCESS | 1662 | 05:37:50 INFO - TEST-INFO | 1662: exit 0 05:37:50 INFO - TEST-OK | dromaeo_css | took 1113485ms 05:37:50 INFO - TEST-START | kraken 05:37:50 INFO - operating with platform_type : mac_ 05:37:50 INFO - Initialising browser for kraken test... 05:37:50 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpB7X9Hw/profile http://localhost:49272/getInfo.html) 05:37:53 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 05:37:53 INFO - PROCESS | 1672 | colorDepth:24 05:37:53 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 05:37:53 INFO - PROCESS | 1672 | __metrics 05:37:53 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:37:53 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:37:53 INFO - TEST-INFO | 1672: exit 0 05:37:53 INFO - Browser initialized. 05:37:53 INFO - Running cycle 1/1 for kraken test... 05:37:53 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpB7X9Hw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:06 INFO - PROCESS | 1674 | RSS: Main: 238870528 05:38:06 INFO - PROCESS | 1674 | 05:38:08 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:09 INFO - PROCESS | 1674 | RSS: Main: 286744576 05:38:09 INFO - PROCESS | 1674 | 05:38:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 05:38:13 INFO - PROCESS | 1674 | RSS: Main: 295833600 05:38:13 INFO - PROCESS | 1674 | 05:38:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 05:38:17 INFO - PROCESS | 1674 | RSS: Main: 384016384 05:38:17 INFO - PROCESS | 1674 | 05:38:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 05:38:19 INFO - PROCESS | 1674 | RSS: Main: 285446144 05:38:19 INFO - PROCESS | 1674 | 05:38:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:22 INFO - PROCESS | 1674 | RSS: Main: 288833536 05:38:22 INFO - PROCESS | 1674 | 05:38:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:26 INFO - PROCESS | 1674 | RSS: Main: 330366976 05:38:26 INFO - PROCESS | 1674 | 05:38:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:30 INFO - PROCESS | 1674 | RSS: Main: 332169216 05:38:30 INFO - PROCESS | 1674 | 05:38:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 05:38:33 INFO - PROCESS | 1674 | RSS: Main: 328663040 05:38:33 INFO - PROCESS | 1674 | 05:38:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:38:36 INFO - PROCESS | 1674 | RSS: Main: 315678720 05:38:36 INFO - PROCESS | 1674 | 05:38:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:38:38 INFO - PROCESS | 1674 | RSS: Main: 322957312 05:38:38 INFO - PROCESS | 1674 | 05:38:40 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:38:41 INFO - PROCESS | 1674 | RSS: Main: 325349376 05:38:41 INFO - PROCESS | 1674 | 05:38:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:45 INFO - PROCESS | 1674 | RSS: Main: 330067968 05:38:45 INFO - PROCESS | 1674 | 05:38:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:48 INFO - PROCESS | 1674 | RSS: Main: 327708672 05:38:48 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 05:38:50 INFO - PROCESS | 1674 | RSS: Main: 374341632 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | __start_tp_report 05:38:50 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 05:38:50 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 05:38:50 INFO - PROCESS | 1674 | |i|pagename|runs| 05:38:50 INFO - PROCESS | 1674 | |0;ai-astar;112;106;111;113;112;112;119;154;112;113 05:38:50 INFO - PROCESS | 1674 | |1;audio-beat-detection;156;118;117;198;116;114;140;116;152;118 05:38:50 INFO - PROCESS | 1674 | |2;audio-dft;183;184;186;184;184;183;183;185;180;183 05:38:50 INFO - PROCESS | 1674 | |3;audio-fft;78;57;61;138;59;56;60;59;123;58 05:38:50 INFO - PROCESS | 1674 | |4;audio-oscillator;92;77;142;79;93;76;102;79;77;106 05:38:50 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;88;86;92;86;84;129;133;85;90;86 05:38:50 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;94;94;93;93;95;94;93;94;97 05:38:50 INFO - PROCESS | 1674 | |7;imaging-desaturate;85;84;86;85;83;86;84;83;85;83 05:38:50 INFO - PROCESS | 1674 | |8;json-parse-financial;55;54;57;55;55;56;57;117;55;54 05:38:50 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;64;75;79;77;73;70;69;70;71;69 05:38:50 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;85;85;72;86;73;97;95;76;106;106 05:38:50 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;140;139;136;134;134;134;139;142;241;137 05:38:50 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;165;163;164;164;167;163;164;165;165;184 05:38:50 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;66;66;66;67;68;66;66;65;65;67 05:38:50 INFO - PROCESS | 1674 | __end_tp_report 05:38:50 INFO - PROCESS | 1674 | __start_cc_report 05:38:50 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,2330 05:38:50 INFO - PROCESS | 1674 | __end_cc_report 05:38:50 INFO - PROCESS | 1674 | __startTimestamp1464093530913__endTimestamp 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | ------- Summary: start ------- 05:38:50 INFO - PROCESS | 1674 | Number of tests: 14 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:116.40 Median:112.50 stddev:13.57 (12.1%) stddev-sans-first:14.30 05:38:50 INFO - PROCESS | 1674 | Values: 112.0 106.0 111.0 113.0 112.0 112.0 119.0 154.0 112.0 113.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:129.00 stddev:27.46 (21.3%) stddev-sans-first:28.00 05:38:50 INFO - PROCESS | 1674 | Values: 156.0 118.0 117.0 198.0 116.0 114.0 140.0 116.0 152.0 118.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.58 (0.9%) stddev-sans-first:1.67 05:38:50 INFO - PROCESS | 1674 | Values: 183.0 184.0 186.0 184.0 184.0 183.0 183.0 185.0 180.0 183.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:74.90 Median:59.00 stddev:30.16 (51.1%) stddev-sans-first:31.97 05:38:50 INFO - PROCESS | 1674 | Values: 78.0 57.0 61.0 138.0 59.0 56.0 60.0 59.0 123.0 58.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:78.00 stddev:20.66 (26.5%) stddev-sans-first:21.91 05:38:50 INFO - PROCESS | 1674 | Values: 92.0 77.0 142.0 79.0 93.0 76.0 102.0 79.0 77.0 106.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:86.00 stddev:18.68 (21.7%) stddev-sans-first:19.59 05:38:50 INFO - PROCESS | 1674 | Values: 88.0 86.0 92.0 86.0 84.0 129.0 133.0 85.0 90.0 86.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:1.25 (1.3%) stddev-sans-first:1.27 05:38:50 INFO - PROCESS | 1674 | Values: 93.0 94.0 94.0 93.0 93.0 95.0 94.0 93.0 94.0 97.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:85.00 stddev:1.17 (1.4%) stddev-sans-first:1.22 05:38:50 INFO - PROCESS | 1674 | Values: 85.0 84.0 86.0 85.0 83.0 86.0 84.0 83.0 85.0 83.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:55.00 stddev:19.53 (35.5%) stddev-sans-first:20.57 05:38:50 INFO - PROCESS | 1674 | Values: 55.0 54.0 57.0 55.0 55.0 56.0 57.0 117.0 55.0 54.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.00 stddev:4.40 (6.1%) stddev-sans-first:3.68 05:38:50 INFO - PROCESS | 1674 | Values: 64.0 75.0 79.0 77.0 73.0 70.0 69.0 70.0 71.0 69.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.00 stddev:12.58 (14.8%) stddev-sans-first:13.30 05:38:50 INFO - PROCESS | 1674 | Values: 85.0 85.0 72.0 86.0 73.0 97.0 95.0 76.0 106.0 106.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:139.00 stddev:32.93 (23.7%) stddev-sans-first:34.82 05:38:50 INFO - PROCESS | 1674 | Values: 140.0 139.0 136.0 134.0 134.0 134.0 139.0 142.0 241.0 137.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.40 Median:165.00 stddev:6.29 (3.8%) stddev-sans-first:6.65 05:38:50 INFO - PROCESS | 1674 | Values: 165.0 163.0 164.0 164.0 167.0 163.0 164.0 165.0 165.0 184.0 05:38:50 INFO - PROCESS | 1674 | 05:38:50 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 05:38:50 INFO - PROCESS | 1674 | Values: 66.0 66.0 66.0 67.0 68.0 66.0 66.0 65.0 65.0 67.0 05:38:50 INFO - PROCESS | 1674 | -------- Summary: end -------- 05:38:50 INFO - PROCESS | 1674 | 05:38:51 INFO - TEST-INFO | 1674: exit 0 05:38:51 INFO - TEST-OK | kraken | took 60872ms 05:38:51 INFO - SUITE-END | took 1174s 05:38:52 INFO - Completed test suite (00:19:34) 05:38:52 INFO - javascript benchmark 05:38:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3468.9479657805136, 3476.6001909780384, 3514.033640748792, 3532.434810430331, 3558.341706784245, 5680.460356266148, 5683.687397193158, 5688.033034384164, 5756.9424305756875, 5757.510611598014, 5691.345495268046, 5748.511376532989, 5757.856053598662, 5765.1928729977835, 5766.106253530709, 4393.604575588196, 5215.791368345262, 5234.005538947587, 5245.4754524547625, 5265.420803711589, 4480.619147372461, 5251.264822924793, 5278.788848446076, 5281.9207711884355, 5369.865753356158, 5312.309399778023, 5362.865928351803, 5373.382061062969, 5379.273798037274, 5379.6503227290295, 64.4677966792714, 5251.159814429673, 5271.841844744664, 5298.178782288732, 5366.8926621467745, 5214.5306922376785, 5239.1355426354985, 5242.5281724644965, 5277.577793776489, 5280.049591073605, 5526.944730552683, 5581.74882130301, 5656.066748986413, 5670.0360938640115, 5717.571182161355, 6106.511479081707, 6108.633481991094, 6219.160413344186, 6219.253689557282, 6230.065490176465, 5681.23303354046, 5925.4370834770625, 6129.79389316787, 6199.845003874894, 6211.409916057967, 5920.911186332209, 5942.61373010753, 5944.286685597756, 5947.078202878604, 5951.434613711691, 6139.553468857682, 6180.598261113103, 6249.843753906189, 6274.152989346379, 6279.403456671609, 4852.611791056707, 6108.755649774048, 6124.930239974474, 6185.443310102112, 6205.069239614094, 4684.344191813149, 5999.4900433463645, 6242.719077641517, 6267.310595834455, 6286.874262514724, 5328.946710532845, 6054.182685337511, 6118.663473508911, 6185.288691800411, 6255.718492667841, 5077.492250774893, 6205.441510264098, 6219.346968568326, 6226.159468471698, 6237.625742455467, 4958.528939750718, 4961.70229786214, 4964.67729597575, 4970.204767237225, 5003.399592048978, 4443.089166720879, 4447.421835161408, 4466.888327791767, 4471.575200355961, 4479.193745125908, 5601.159826026129, 5668.0, 5674.716264186856, 5727.742251598688, 5739.626924249994, 6208.906866397007, 6326.620402775849, 6327.746890124344, 6329.556931014877, 6330.873382532323, 5617.606767526234, 5701.71491425427, 5750.827475175752, 5752.971235143881, 5821.825345239649, 5390.54180394663, 5430.647007944471, 5432.348118225838, 5440.347158341025, 5455.863603409946, 6247.718852651551, 6358.459530939922, 6361.077643741632, 6382.904256436064, 6386.936130638727, 5692.686902220417, 6146.078088286793, 6161.014237722122, 6323.241211054519, 6326.082718005955, 6227.408396202353, 6281.811545653638, 6286.0, 6302.401271879165, 6310.305866354789, 6096.695165241631, 6134.907976380268, 6155.261368635793, 6155.938440615537, 6262.561620686508, 5999.730012149551, 6038.456538911519, 6101.186783078283, 6215.285242197025, 6238.75044998195, 5838.503727183067, 5838.5621078418435, 5843.2695913010875, 5845.707714614337, 5895.174675545341, 5651.943480565306, 5799.101139323411, 5808.477237048686, 5813.941860581509, 5874.706264686663, 5739.799107031234, 5795.246617939641, 5917.201177840936, 5954.3152537458745, 5979.1928089707335, 6122.571420000557, 6135.754569817157, 6140.785072522441, 6143.539234557516, 6151.738893526726, 5973.701314934323, 5982.641041537521, 6005.489533389711, 6021.0, 6022.09668549721], "value": 5628.722324450237, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.4425256071555, 508.2604810001446, 508.63886640485254, 516.9379674439068, 520.7838746920028, 30357.39285214297, 30629.38741225174, 32577.696892124317, 42752.78623606878, 43912.780436097775, 45204.19183232675, 45659.63021109364, 45786.542134578645, 45884.0, 46044.76977615107, 9492.955774864777, 9679.806403871919, 9697.757556061084, 9698.369605975624, 9719.028097190296, 8583.442076265053, 8809.295256379506, 9238.491882946435, 11595.362255075968, 11821.70445738859, 11671.941640291787, 11744.706382340468, 11747.001504872096, 11751.7649647007, 11784.175107742461, 8568.614412351397, 11595.072394208442, 11635.185537012412, 11640.243384180087, 11646.708832279219, 11268.774624507463, 11598.246114002563, 11609.24539904912, 11783.646490605295, 11806.822897656542, 36332.0, 40938.38592421086, 41454.37818432726, 41884.79057604723, 42029.57970420287, 3182.315802102536, 3217.4530329843747, 3236.2071292533246, 3335.399628066947, 3514.595821480537, 28999.275018124717, 29116.27209319763, 29697.554536681968, 29739.553906691417, 29759.40481190364, 2700.4599080184043, 2727.1273192578383, 2744.725527447259, 2764.3365592257915, 2797.608334833125, 392984.0, 393921.0607893913, 402837.0, 403209.0, 403730.0, 63594.0460893087, 63950.0407493888, 63983.0, 64139.0, 64149.67925160344, 35908.0, 36521.0, 36635.26729465396, 36740.0, 67047.32952670411, 16057.277422516016, 16059.919700401424, 16151.676966460605, 16174.0, 16195.352185912667, 11672.299662020307, 11904.107191960638, 12865.005924081783, 12944.029197810014, 12989.480420783251, 16483.34066637345, 19733.210671572975, 19807.405777826687, 20005.899970500053, 20733.27433539849, 7740.8451830964195, 7790.415718821111, 7890.921090789018, 7940.960295198487, 7977.441579089408, 841.6086519768337, 896.4666023715879, 980.9117179453883, 1017.1405162637707, 1032.2361452525076, 29679.0, 29979.250518737204, 30067.646955887038, 30601.846990764905, 30610.54084188739, 974.70758772368, 1045.6706137566794, 1050.7863417752508, 1085.7557049663762, 1101.3336931156534, 15043.323050462755, 15364.923175384052, 15424.845751542565, 15609.765853512206, 15740.763888541682, 661780.0, 669790.0, 672256.0, 676989.0, 680065.0, 61252.38747612556, 61294.38705612887, 63154.368456315766, 63190.73618527695, 63242.36757632457, 1304.6999190186311, 1321.6497628128554, 1422.8434872164053, 1424.2166808255624, 1461.503088949741, 25777.226683199533, 29555.261118472212, 30730.231744206572, 31103.377932441676, 31201.0, 51301.74349128231, 51449.0, 51789.22316165336, 52016.0, 52089.0, 521820.0, 523428.0, 524573.0, 525114.0, 525276.0, 23873.04507819668, 25390.0, 26312.473750525267, 26660.8666956664, 27685.169444916686, 47814.760926195144, 47869.5213047879, 47960.7601961988, 48363.75818120887, 48413.5158648409, 37998.0, 38162.960595634475, 38485.03787405337, 38803.80598096991, 39533.2093358137, 37055.444168338036, 37460.625393745715, 38007.619923800405, 38061.238775223785, 38458.42312365261, 37976.81011594924, 38177.2364552713, 38177.66378176751, 39172.02069948274, 40056.998575035854, 33010.009699709044, 34045.0, 34140.0, 34446.48330275098, 34522.65477345194, 20556.69164962497, 23885.044598215878, 24927.875360623082, 25082.37294067663, 25200.865961032163], "value": 19876.86343358921, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.55674617639283, 337.9476181191913, 379.6696873719863, 388.1886856469979, 402.39640539191214, 16368.017918924872, 20194.0, 21151.048202830872, 21216.4695882603, 22026.0, 17599.82400175998, 21108.788912110875, 21311.57376852466, 21919.0, 22072.33782986513, 5775.393583673716, 5894.440028197314, 6093.329733729286, 6117.480014198798, 6143.539234557404, 5943.435373639497, 6034.24571928509, 6081.935661259284, 6116.08258761185, 6129.969350153243, 5331.786728530854, 6061.727222274986, 6067.089936509515, 6082.5742198046155, 6087.695615219243, 5858.970705146447, 6003.339632640406, 6055.5761096723245, 6066.969665151646, 6077.5137988961, 5180.5337519623035, 5182.714950677711, 5192.506711862367, 5261.421243663194, 5269.947300527022, 24990.5001899961, 25313.240602781945, 25430.98276068955, 25457.0, 25918.48163036729, 2165.436986383552, 2243.4054975431523, 2280.942976425586, 2285.611446054175, 2396.341006223285, 12005.819912701316, 14905.180215088165, 14978.62553436162, 15098.094114353064, 15530.9223453882, 1730.4030109612163, 1783.7681101456858, 1829.0945981738992, 1829.9634007319912, 1873.4473330367439, 76610.0, 101626.9837301625, 113331.0, 120130.0, 125307.0, 9510.858696956271, 10527.157827373792, 10653.520591573242, 10657.094146997591, 10757.085647720032, 13969.860301396857, 14023.579292621238, 14035.087719298213, 14038.227897465737, 14130.50543230982, 8375.49747015169, 8600.612972416257, 8680.696175633822, 8748.300135989106, 8835.160659737314, 8690.522021288763, 8716.477011379211, 8720.032385264689, 8847.46915185091, 8874.29005679544, 6237.407446292595, 6279.215098112736, 6674.399304062561, 6833.241717055599, 6845.760398386132, 1538.2462593329412, 1616.585487698264, 1771.6269890834449, 1843.7510936023734, 1878.445858471754, 811.4319976016811, 848.9642635984213, 920.7283851263893, 990.6829814459304, 1029.45953374479, 17135.0, 20243.797562024487, 20663.070161842752, 20698.79301206999, 20716.792832071787, 955.3408148377597, 983.2822039910905, 989.1987490133015, 1000.8548760429696, 1028.7788125552893, 10999.780004399867, 11272.380019099028, 11292.830607540893, 11317.434128293553, 11339.433028348549, 519589.2061619079, 570139.0, 581418.0, 607540.9622951857, 608010.0, 119353.40323298328, 120658.79341206652, 121664.0, 122441.38779306046, 126079.73920260863, 1012.3824467075078, 1032.0659802878406, 1051.75283808305, 1055.6569115037644, 1060.7242117049623, 16461.423850165185, 18188.818111818713, 18372.897626142476, 18691.90654046721, 18778.718319225496, 47936.28095578499, 48781.51218487912, 50002.74993125055, 50174.0, 50251.24623130583, 71444.64277678578, 71744.0, 71993.0, 73121.0, 73501.6324918372, 7625.161232264336, 7786.649600767866, 7918.841623167619, 8016.599170041358, 8223.79440513968, 9744.902550974399, 11358.602448914578, 12260.26438413698, 12452.501899923902, 12614.306213158365, 9622.51887405613, 9780.924098349318, 10417.42704151279, 10645.308054976402, 10725.517351719347, 7961.601919903866, 10289.176865850564, 10453.634122805664, 10556.366618002945, 10652.733681658021, 9481.952590237004, 10529.683789526309, 10834.620788272372, 10861.402622855503, 10985.61550345734, 7953.204679532232, 7979.9202007979175, 8121.228483294078, 8220.219079187467, 8229.341652667652, 9036.689679996494, 9340.813183736424, 9940.652077177265, 10690.0, 10708.143348532254], "value": 9516.99734131116, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.0463879922859, 1554.5025591810622, 1571.55210764932, 1803.9549011274717, 1946.04643724575, 7937.642806073726, 8524.147585241473, 8815.911840881588, 8820.117988201193, 8824.69113581024, 8385.245327920486, 8703.390762646617, 8706.303495720344, 8816.867746983784, 8825.02924678285, 2297.322289924468, 2335.964960525585, 2341.648752687102, 2350.529894021194, 2354.4702441950594, 2312.283192210412, 2349.823763217757, 2358.3278765551822, 2363.267387109993, 2368.0527788884497, 1935.9709604355946, 2306.5963456395066, 2314.3404129823, 2322.0, 2331.428799944016, 2308.2613563659634, 2314.5314964351765, 2351.8588884666806, 2354.19957214547, 2356.7761062699014, 2346.9647955280684, 2360.3155085025323, 2377.940551486209, 2381.2380038387723, 2386.140989243871, 8471.788205294917, 8756.781080472976, 8767.605457754416, 8773.42972706772, 8791.208791208757, 5319.175527793198, 5429.049916264638, 5483.341998960111, 5487.094629386146, 5496.780128794843, 3232.3212125453683, 3270.8528116234825, 3273.9508907366408, 3494.930101397958, 3560.2701446203464, 1011.5903059261011, 1025.7333093395696, 1028.1415018459595, 1034.2088302448633, 1035.9741006474824, 9748.512574371252, 11435.256708313933, 11541.826872596917, 11648.068154547616, 11677.591284305094, 4741.83403580873, 4752.500987396343, 4790.616750659939, 4848.296996935424, 4882.414110306715, 10201.438920859424, 10240.0, 10262.179025677928, 10264.076233139054, 10293.433861137559, 5394.0, 5427.674339539641, 5428.022955867903, 5438.021156191923, 5439.129739241703, 4441.777911104432, 4603.562661547147, 4776.9761151194025, 4798.2082956312515, 4958.330625365529, 3978.224246271949, 3999.1801680655394, 4068.3287257602165, 4089.9182016359505, 4188.476440444944, 1270.1490001698885, 1281.2568710148091, 1286.9742605148033, 1295.2617008305176, 1304.3217526885967, 637.7321524959528, 639.3478651775164, 864.3862857371337, 914.5960949941807, 941.0260380506144, 4530.501644819067, 5293.115252187567, 5711.200431939532, 5776.566757493205, 5910.674912879746, 765.4144579396766, 768.8428914483666, 919.2416256588208, 925.1303774451993, 978.5351957820121, 3322.368749937504, 3797.0697179191275, 3823.770573765583, 3859.286032084073, 3923.2349691810396, 10487.790244195074, 10712.78574428507, 10738.463076846283, 10755.78488430227, 10913.618023369145, 10463.319884207502, 10804.0, 10809.567617295377, 10932.016118549369, 10945.945270273756, 8232.547209903512, 8289.005319361595, 8304.6678132874, 8372.706955256537, 8396.580170991427, 5669.291338582677, 5679.886402271849, 5695.0, 5836.620619659709, 5958.404159584007, 9840.60637574489, 10168.084872361524, 10293.639722609672, 10304.587816487257, 10304.742381440525, 10293.845592316271, 10457.84313235287, 10460.581576736846, 10460.68617941463, 10485.423301718478, 7021.1574611047, 7058.823529411806, 7118.537295075804, 7192.676329565287, 7210.206877243394, 8234.547099909563, 8418.789530261793, 8424.536650484282, 8430.367722420966, 8437.325013998743, 6094.7562097517375, 7279.417646588155, 7465.4027677786735, 7517.02278703765, 7624.809379765328, 7222.89165662526, 7314.122305323182, 7332.633368331669, 7412.258774122544, 7492.812679683051, 6922.58464492132, 6970.756023539152, 7036.5074444790425, 7038.8944165838575, 7043.6830342633675, 4630.560096790799, 4713.646476514206, 4729.929051064168, 4788.3056956741775, 4833.154198015263, 4206.978965105155, 4875.3174555562955, 4918.778654960607, 4968.254761785761, 5043.52086551777], "value": 4534.34144348748, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.1296240901384, 306.8603785277698, 316.38147421790404, 321.866425433445, 321.86642543344516, 5133.766811437027, 5322.749153948819, 5389.137737961922, 5404.189371594258, 5443.374011988622, 4246.872593822182, 4395.0, 5293.6294459387855, 5340.463279942026, 5342.412334643186, 3246.8376581170965, 4136.4695062826795, 4136.5036195656485, 4137.91724165518, 4153.273177193994, 3223.7098661120494, 3265.4611989021782, 4142.958570414303, 4148.771817550033, 4164.500259968793, 3523.083998160484, 4103.712740108193, 4104.296716862514, 4107.876763697094, 4129.7315674481215, 3709.6830625128096, 3785.1861849702173, 4089.1821635672986, 4095.2219078375015, 4132.297509423405, 3558.4128618777872, 3572.8384327482017, 3573.213892943548, 3597.3704601694863, 3603.828755654423, 4172.7287726298, 4998.775544882493, 5084.466131056222, 5129.615278854064, 5204.34945631796, 4543.250363689987, 4573.931391029138, 4580.198465268565, 4593.311003349491, 4595.126925884105, 4647.37260469839, 4686.461056978457, 4908.361912951329, 5304.38999515053, 5506.449355064502, 3427.0746898337557, 3477.139730244307, 3731.8320675569394, 3782.5650050244026, 3822.522184726909, 5349.893002139936, 5549.833504994816, 5771.56713246504, 5824.504917082053, 5864.472197502203, 3846.5576458707606, 3879.7284190106984, 3883.66988805949, 3890.358090915024, 3913.3738601824143, 3337.766356355032, 3510.9116173986145, 3634.7823479134786, 3899.9610003900166, 3910.687145028391, 3880.3791393377487, 4554.567316104964, 4584.679072464962, 4597.586217240398, 4598.655100867449, 4756.738379389098, 5084.0, 5357.276767636397, 5727.971360143172, 5731.656100633975, 1269.2574843716345, 1308.672831792052, 1314.737052589486, 1337.9866201338057, 1355.600097971101, 737.9114506259283, 768.6656304507557, 785.4305628419351, 797.9586639435477, 816.3061397811931, 3909.8240579173435, 3935.4490371348033, 4120.1553681495225, 4405.669574781904, 4419.779011049434, 4503.324501324828, 4722.480527142012, 4838.0082083172865, 4858.3441235432765, 4859.198232291703, 3735.122246272124, 4042.4936539345795, 4425.004374015885, 4439.4228750262255, 4507.0, 2232.983992283507, 2323.7211534615953, 2329.718990619827, 2557.923262302134, 2576.561984462646, 4307.698461107692, 4458.238995596721, 4671.906561868744, 4739.715617062987, 4932.950670493249, 3976.6619837313574, 4286.5070516890955, 4592.668126243419, 4888.653446345703, 5026.572741317029, 4572.199865023674, 5055.8988820223385, 5177.456367081402, 6132.877342453126, 6134.938650613437, 5060.380678182946, 5317.0, 5442.836714898559, 5889.5582831286965, 5913.497352724895, 5249.737513124405, 5571.804986825442, 5804.796832110856, 5846.56150788684, 5909.197694703157, 5095.573214526517, 5369.301990741179, 5452.700101494456, 5579.903153749708, 6089.695515224133, 3842.51968503937, 3855.2482265958993, 3891.6379267256234, 3909.6285852843935, 3917.177392747441, 5078.5175408336145, 5460.645058071213, 5490.505854473116, 5528.75120619565, 5952.375000624872, 4342.935980684626, 5593.328800543961, 5609.887802244014, 5639.830805075854, 5670.376258611467, 5636.872625474839, 5679.290088738908, 5689.089745640677, 5692.5445964321925, 5745.913811292918, 4698.875247960205, 5396.3524377075, 5566.359868615161, 5613.494785469327, 5617.578681598815, 4786.760661966958, 5035.647504674784, 5212.895742085213, 5232.790688372423, 5640.576956728344, 4586.770661466847, 5596.356419011866, 5604.663720176803, 5605.775768969195, 5615.747291371979], "value": 3916.8207907026344, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.8102440804371, 723.52247516639, 727.3272223193547, 732.765515035189, 736.4550183371359, 14671.559853204395, 14995.175265360414, 15546.766798498018, 15738.370465181379, 15764.369425223005, 14372.0, 14477.493287734931, 14532.491362802304, 14536.491222807204, 14546.418143274257, 718.4001358865354, 743.126825979472, 890.7238755985633, 910.2263076385086, 911.3210658059754, 885.3846576629245, 914.2229105260509, 915.9129882661136, 919.5448253114718, 920.8158368326328, 962.4658314635375, 969.050330675938, 969.2827307792219, 970.8252514547379, 971.0774763974252, 754.3964828137523, 896.4173287363201, 923.4736200365794, 967.3180407812475, 967.9884520675911, 686.7939618114546, 687.7249100359846, 690.2303931116819, 690.7478770248823, 693.1370443797454, 14506.129632221995, 14791.40834366624, 14815.48145814902, 14823.851761482356, 14829.555113346616, 12129.636110916686, 12163.209391389531, 12280.263184208976, 12372.690682732913, 12412.689682757913, 10789.56841726338, 10853.945730271376, 10875.238733288674, 10880.836787448194, 10964.835527467094, 3938.9212215755524, 3961.485006949107, 3968.6825053995613, 4085.9795701021603, 4093.6111069448357, 79683.0, 80857.19142808614, 81261.0, 81667.0, 81709.59145204295, 9084.22784063362, 9440.48077355752, 9484.762880928032, 9616.75958101053, 9859.457729824786, 20407.183712651327, 21040.78959210419, 21158.0, 21266.25568105109, 21305.573888522453, 6006.189164462829, 6909.412699920463, 6940.826479337956, 6956.930430695628, 6964.895526567106, 3986.6412022918075, 4011.5787842276723, 4033.1731994940965, 4035.3946907963455, 4042.2319759245647, 3269.2807582331848, 3367.2760356523463, 3368.1579605098727, 3368.2758206985613, 3374.4600863861665, 597.8266302772234, 610.1457958857635, 614.8247749391402, 618.1593033474471, 620.6648409858716, 457.4761897627198, 492.2588328701909, 525.1335296760376, 553.582045555611, 556.3185098254687, 6497.18785151856, 7413.7405190819145, 7574.280443357872, 7714.807129821799, 7841.843163136819, 536.5010540197653, 537.8198303568348, 552.8009916430082, 559.3288054334814, 569.4760820045568, 3664.3037822814113, 3679.7056235501095, 3693.6306369362846, 3766.3408903442337, 3787.62123787619, 52234.73882630563, 79398.6030069846, 91153.54423227975, 92202.53898730464, 93888.0, 55298.72350638221, 80925.59537202277, 81678.59160704158, 82512.0, 82897.0, 68422.65788671025, 68485.3151468489, 68955.31044689589, 69110.30889691038, 70399.29600704029, 7215.747448839265, 7309.561426314438, 7315.085614298213, 7359.448041397024, 7436.702531898663, 44452.333215001156, 45450.772746136056, 45807.31289030734, 45840.77079614581, 46014.769926150155, 55446.0, 56914.0, 57084.85830283454, 57133.143002855824, 58216.0, 10550.261481696207, 10884.836727449254, 10943.343399396063, 11070.63156377522, 11254.94372528132, 12010.819837702616, 12074.45664945062, 12356.567520137112, 12776.10567260283, 13153.802692959805, 10409.68770936873, 10669.626563070255, 11520.82718759236, 11652.067834573045, 11889.940550297193, 9983.400995939975, 10024.0, 10222.6422075227, 10357.223208259562, 12341.691457713629, 10077.899221007696, 10115.696529104138, 10330.741731456774, 10382.221333400177, 10546.419946902994, 8234.876476852973, 8358.08061113265, 8552.0, 8684.956575217084, 8771.473711577326, 8503.829923401374, 8537.402381833212, 8591.914080859362, 8605.526696031779, 8617.181367770052], "value": 6647.940540576852, "unit": "score"}], "name": "dromaeo_css", "value": 7077.939653977471, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 111.0, 113.0, 112.0, 112.0, 119.0, 154.0, 112.0, 113.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 118.0, 117.0, 198.0, 116.0, 114.0, 140.0, 116.0, 152.0, 118.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 186.0, 184.0, 184.0, 183.0, 183.0, 185.0, 180.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 61.0, 138.0, 59.0, 56.0, 60.0, 59.0, 123.0, 58.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 77.0, 142.0, 79.0, 93.0, 76.0, 102.0, 79.0, 77.0, 106.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 92.0, 86.0, 84.0, 129.0, 133.0, 85.0, 90.0, 86.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 93.0, 95.0, 94.0, 93.0, 94.0, 97.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 85.0, 83.0, 86.0, 84.0, 83.0, 85.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 57.0, 55.0, 55.0, 56.0, 57.0, 117.0, 55.0, 54.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 79.0, 77.0, 73.0, 70.0, 69.0, 70.0, 71.0, 69.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 72.0, 86.0, 73.0, 97.0, 95.0, 76.0, 106.0, 106.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 136.0, 134.0, 134.0, 134.0, 139.0, 142.0, 241.0, 137.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 164.0, 164.0, 167.0, 163.0, 164.0, 165.0, 165.0, 184.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 68.0, 66.0, 66.0, 65.0, 65.0, 67.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1477.3999999999999, "alertThreshold": 2.0}]} 05:38:52 INFO - Return code: 0 05:38:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:38:52 ERROR - # TBPL SUCCESS # 05:38:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:52 INFO - 2016-05-24 05:38:52.251 screenresolution[1678:10220] starting screenresolution argv=screenresolution get 05:38:52 INFO - 2016-05-24 05:38:52.254 screenresolution[1678:10220] Display 0: 1600x1200x32@60 05:38:52 INFO - 2016-05-24 05:38:52.260 screenresolution[1679:10223] starting screenresolution argv=screenresolution list 05:38:52 INFO - Available Modes on Display 0 05:38:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:52 INFO - Intel Iris: 05:38:52 INFO - Chipset Model: Intel Iris 05:38:52 INFO - Type: GPU 05:38:52 INFO - Bus: Built-In 05:38:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:52 INFO - Vendor: Intel (0x8086) 05:38:52 INFO - Device ID: 0x0a2e 05:38:52 INFO - Revision ID: 0x0009 05:38:52 INFO - Displays: 05:38:52 INFO - Display: 05:38:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:52 INFO - Display Serial Number: 1600x1200 60 05:38:52 INFO - Main Display: Yes 05:38:52 INFO - Mirror: Off 05:38:52 INFO - Online: Yes 05:38:52 INFO - Rotation: Supported 05:38:52 INFO - Return code: 0 05:38:52 INFO - Running post-action listener: _resource_record_post_action 05:38:52 INFO - ##### 05:38:52 INFO - ##### Finished run-tests step (success) 05:38:52 INFO - ##### 05:38:52 INFO - Running post-run listener: _resource_record_post_run 05:38:52 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37885440; Write bytes: 696500224; Read time: 395; Write time: 2057 05:38:52 INFO - TinderboxPrint: CPU usage
53.9% 05:38:52 INFO - TinderboxPrint: I/O read bytes / time
37,885,440 / 395 05:38:52 INFO - TinderboxPrint: I/O write bytes / time
696,500,224 / 2,057 05:38:52 INFO - TinderboxPrint: CPU idle
2,233.1 (46.1%) 05:38:52 INFO - TinderboxPrint: CPU system
971.3 (20.1%) 05:38:52 INFO - TinderboxPrint: CPU user
1,637.3 (33.8%) 05:38:52 INFO - TinderboxPrint: Swap in / out
434,208,768 / 0 05:38:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379765248; Write bytes: 373020672; Read time: 28107; Write time: 691 05:38:52 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36922880; Write bytes: 298514432; Read time: 381; Write time: 1303 05:38:52 INFO - Running post-run listener: _upload_blobber_files 05:38:52 INFO - Blob upload gear active. 05:38:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:52 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 05:38:52 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:38:52 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:38:53 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:38:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:38:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:55 INFO - (blobuploader) - INFO - Done attempting. 05:38:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:38:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:38:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:55 INFO - (blobuploader) - INFO - Done attempting. 05:38:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:38:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:58 INFO - (blobuploader) - INFO - Done attempting. 05:38:58 INFO - (blobuploader) - INFO - Iteration through files over. 05:38:58 INFO - Return code: 0 05:38:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:38:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:38:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2e317d07f6a908a1d0f27f1671abfd54159afaa363211db5d87d7b8c8aa46d6a3ebcecfb2d6dd2fcccd6c8ae6de3cda1c0b3ef2bc3748b17c5ecc7789b41d30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5358169f4805a01e75335144f65c0bb7475b4e61380238969d613ef72624f7f2cff56b0b586c96731d91140e10ca9e3e83ea1be31062d7a23b29f026e6655c78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5da455186429a7d982fb92c07a5eabf4df8f70115000a5dfcafcd30efd67530b9b8f4f95eec34213b853fcf89b267e7a69914c415f34d1d9af0ce37ebac69a1f"} 05:38:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:38:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:38:58 INFO - Contents: 05:38:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2e317d07f6a908a1d0f27f1671abfd54159afaa363211db5d87d7b8c8aa46d6a3ebcecfb2d6dd2fcccd6c8ae6de3cda1c0b3ef2bc3748b17c5ecc7789b41d30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5358169f4805a01e75335144f65c0bb7475b4e61380238969d613ef72624f7f2cff56b0b586c96731d91140e10ca9e3e83ea1be31062d7a23b29f026e6655c78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5da455186429a7d982fb92c07a5eabf4df8f70115000a5dfcafcd30efd67530b9b8f4f95eec34213b853fcf89b267e7a69914c415f34d1d9af0ce37ebac69a1f"} 05:38:58 INFO - Running post-run listener: copy_logs_to_upload_dir 05:38:58 INFO - Copying logs to upload dir... 05:38:58 INFO - mkdir: /builds/slave/test/build/upload/logs 05:38:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.190049 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-24 05:38:58.257316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 05:38:58.261419) ========= 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.D62J5PLVMV/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.SwrjMJpjzk/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2e317d07f6a908a1d0f27f1671abfd54159afaa363211db5d87d7b8c8aa46d6a3ebcecfb2d6dd2fcccd6c8ae6de3cda1c0b3ef2bc3748b17c5ecc7789b41d30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5358169f4805a01e75335144f65c0bb7475b4e61380238969d613ef72624f7f2cff56b0b586c96731d91140e10ca9e3e83ea1be31062d7a23b29f026e6655c78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5da455186429a7d982fb92c07a5eabf4df8f70115000a5dfcafcd30efd67530b9b8f4f95eec34213b853fcf89b267e7a69914c415f34d1d9af0ce37ebac69a1f"} build_url:https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/F3DIS_0RRhmfYbvH4dISBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2e317d07f6a908a1d0f27f1671abfd54159afaa363211db5d87d7b8c8aa46d6a3ebcecfb2d6dd2fcccd6c8ae6de3cda1c0b3ef2bc3748b17c5ecc7789b41d30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5358169f4805a01e75335144f65c0bb7475b4e61380238969d613ef72624f7f2cff56b0b586c96731d91140e10ca9e3e83ea1be31062d7a23b29f026e6655c78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5da455186429a7d982fb92c07a5eabf4df8f70115000a5dfcafcd30efd67530b9b8f4f95eec34213b853fcf89b267e7a69914c415f34d1d9af0ce37ebac69a1f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 05:38:58.291510) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 05:38:58.291837) ========= 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.D62J5PLVMV/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.SwrjMJpjzk/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.005107 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 05:38:58.340833) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 05:38:58.341165) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 05:38:58.341523) ========= ========= Total master_lag: 0.16 =========