builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0084 starttime: 1464697396.8 results: success (0) buildid: 20160531030258 builduid: 08e5993d0c8940cea11e1323264c8401 revision: 864cdd00360cdf62ea5132a457ee53a17f9e31aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:16.800942) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:16.801954) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:16.802669) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:16.825472) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:16.826133) ========= 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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-31 05:23:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.14M=0.002s 2016-05-31 05:23:16 (6.14 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103728 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:16.955264) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:16.955635) ========= 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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.020049 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:17.005415) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 05:23:17.005810) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 864cdd00360cdf62ea5132a457ee53a17f9e31aa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 864cdd00360cdf62ea5132a457ee53a17f9e31aa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nH9k3pUT3P/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.4DKRSYdYD8/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-31 05:23:17,099 truncating revision to first 12 chars 2016-05-31 05:23:17,100 Setting DEBUG logging. 2016-05-31 05:23:17,100 attempt 1/10 2016-05-31 05:23:17,100 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/864cdd00360c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 05:23:18,202 unpacking tar archive at: mozilla-central-864cdd00360c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.363246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 05:23:18.389954) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:18.390319) ========= script_repo_revision: 864cdd00360cdf62ea5132a457ee53a17f9e31aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:18.390770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:18.391071) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 05:23:18.408036) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-31 05:23:18.408342) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nH9k3pUT3P/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.4DKRSYdYD8/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:23:18 INFO - MultiFileLogger online at 20160531 05:23:18 in /builds/slave/test 05:23:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:23:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:23:18 INFO - {'append_to_log': False, 05:23:18 INFO - 'base_work_dir': '/builds/slave/test', 05:23:18 INFO - 'blob_upload_branch': 'Firefox', 05:23:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:23:18 INFO - 'branch': 'Firefox', 05:23:18 INFO - 'buildbot_json_path': 'buildprops.json', 05:23:18 INFO - 'config_files': ('talos/mac_config.py',), 05:23:18 INFO - 'default_actions': ('clobber', 05:23:18 INFO - 'read-buildbot-config', 05:23:18 INFO - 'download-and-extract', 05:23:18 INFO - 'populate-webroot', 05:23:18 INFO - 'create-virtualenv', 05:23:18 INFO - 'install', 05:23:18 INFO - 'run-tests'), 05:23:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:23:18 INFO - 'download_minidump_stackwalk': True, 05:23:18 INFO - 'download_symbols': 'ondemand', 05:23:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:23:18 INFO - 'tooltool.py': '/tools/tooltool.py', 05:23:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:23:18 INFO - '/tools/misc-python/virtualenv.py')}, 05:23:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:23:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:23:18 INFO - 'installer_path': 'installer.exe', 05:23:18 INFO - 'log_level': 'info', 05:23:18 INFO - 'log_name': 'talos', 05:23:18 INFO - 'log_to_console': True, 05:23:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:23:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:23:18 INFO - 'opt_config_files': (), 05:23:18 INFO - 'pip_index': False, 05:23:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:23:18 INFO - 'cmd': ('bash', 05:23:18 INFO - '-c', 05:23:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:23:18 INFO - 'enabled': True, 05:23:18 INFO - 'halt_on_failure': False, 05:23:18 INFO - 'name': 'check_screen_resolution'},), 05:23:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:23:18 INFO - 'cmd': ('bash', 05:23:18 INFO - '-c', 05:23:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:23:18 INFO - 'enabled': True, 05:23:18 INFO - 'halt_on_failure': False, 05:23:18 INFO - 'name': 'check_screen_resolution'},), 05:23:18 INFO - 'run_cmd_checks_enabled': True, 05:23:18 INFO - 'sps_profile': False, 05:23:18 INFO - 'sps_profile_interval': 0, 05:23:18 INFO - 'suite': 'dromaeojs', 05:23:18 INFO - 'system_bits': '32', 05:23:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:18 INFO - 'title': 't-yosemite-r7-0084', 05:23:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:23:18 INFO - 'use_talos_json': True, 05:23:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:23:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:23:18 INFO - 'work_dir': 'build'} 05:23:18 INFO - ##### 05:23:18 INFO - ##### Running clobber step. 05:23:18 INFO - ##### 05:23:18 INFO - Running pre-action listener: _resource_record_pre_action 05:23:18 INFO - Running main action method: clobber 05:23:18 INFO - rmtree: /builds/slave/test/build 05:23:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:20 INFO - Running post-action listener: _resource_record_post_action 05:23:20 INFO - ##### 05:23:20 INFO - ##### Finished clobber step (success) 05:23:20 INFO - ##### 05:23:20 INFO - ##### 05:23:20 INFO - ##### Running read-buildbot-config step. 05:23:20 INFO - ##### 05:23:20 INFO - Running pre-action listener: _resource_record_pre_action 05:23:20 INFO - Running main action method: read_buildbot_config 05:23:20 INFO - Using buildbot properties: 05:23:20 INFO - { 05:23:20 INFO - "project": "", 05:23:20 INFO - "product": "firefox", 05:23:20 INFO - "script_repo_revision": "production", 05:23:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:23:20 INFO - "repository": "", 05:23:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:23:20 INFO - "buildid": "20160531030258", 05:23:20 INFO - "stage_platform": "macosx64", 05:23:20 INFO - "basedir": "/builds/slave/test", 05:23:20 INFO - "nightly_build": "True", 05:23:20 INFO - "buildnumber": 15, 05:23:20 INFO - "slavename": "t-yosemite-r7-0084", 05:23:20 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:23:20 INFO - "platform": "yosemite_r7", 05:23:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:23:20 INFO - "branch": "mozilla-central", 05:23:20 INFO - "repo_path": "mozilla-central", 05:23:20 INFO - "revision": "864cdd00360cdf62ea5132a457ee53a17f9e31aa", 05:23:20 INFO - "pgo_build": "False", 05:23:20 INFO - "builduid": "08e5993d0c8940cea11e1323264c8401", 05:23:20 INFO - "slavebuilddir": "test" 05:23:20 INFO - } 05:23:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:23:20 INFO - Running post-action listener: _resource_record_post_action 05:23:20 INFO - ##### 05:23:20 INFO - ##### Finished read-buildbot-config step (success) 05:23:20 INFO - ##### 05:23:20 INFO - ##### 05:23:20 INFO - ##### Running download-and-extract step. 05:23:20 INFO - ##### 05:23:20 INFO - Running pre-action listener: _resource_record_pre_action 05:23:20 INFO - Running main action method: download_and_extract 05:23:20 INFO - mkdir: /builds/slave/test/build/tests 05:23:20 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:23:20 INFO - https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:23:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:23:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:23:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:22 INFO - Downloaded 1199 bytes. 05:23:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:23:22 INFO - Using the following test package requirements: 05:23:22 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:23:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:23:22 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:23:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:23:22 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:23:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:23:22 INFO - u'jsshell-mac.zip'], 05:23:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:23:22 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:23:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:23:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:23:22 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:23:22 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:23:22 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:23:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:23:22 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:23:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:23:22 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:23:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:23:22 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:23:22 INFO - https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:23:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:23:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:23:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:23 INFO - Downloaded 18687169 bytes. 05:23:23 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:23:23 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:23:26 INFO - Return code: 0 05:23:26 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:23:26 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:23:26 INFO - https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:23:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:23:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:23:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:27 INFO - Downloaded 18687169 bytes. 05:23:27 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:23:27 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:23:31 INFO - Return code: 0 05:23:31 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:23:31 INFO - https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:23:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:23:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:23:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:32 INFO - Downloaded 10834055 bytes. 05:23:32 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:23:32 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:23:32 INFO - Return code: 0 05:23:32 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:23:32 INFO - https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:23:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:23:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:23:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:23:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/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:23:36 INFO - Downloaded 111138871 bytes. 05:23:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:23:36 INFO - mkdir: /builds/slave/test/properties 05:23:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:36 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:36 INFO - Contents: 05:23:36 INFO - build_url:https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:23:37 INFO - Running post-action listener: _resource_record_post_action 05:23:37 INFO - Running post-action listener: set_extra_try_arguments 05:23:37 INFO - ##### 05:23:37 INFO - ##### Finished download-and-extract step (success) 05:23:37 INFO - ##### 05:23:37 INFO - ##### 05:23:37 INFO - ##### Running populate-webroot step. 05:23:37 INFO - ##### 05:23:37 INFO - Running pre-action listener: _resource_record_pre_action 05:23:37 INFO - Running main action method: populate_webroot 05:23:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:37 INFO - '--tppagecycles', 05:23:37 INFO - '7'], 05:23:37 INFO - 'tests': ['tsvgm']}}, 05:23:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:37 INFO - 'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:37 INFO - 'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['damp', 'tps']}, 05:23:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:37 INFO - 'tests': ['damp', 'tps']}, 05:23:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['dromaeo_dom']}, 05:23:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['a11yr', 05:23:37 INFO - 'ts_paint', 05:23:37 INFO - 'tpaint', 05:23:37 INFO - 'sessionrestore', 05:23:37 INFO - 'sessionrestore_no_auto_restore', 05:23:37 INFO - 'tabpaint']}, 05:23:37 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:37 INFO - 'ts_paint', 05:23:37 INFO - 'tpaint', 05:23:37 INFO - 'sessionrestore', 05:23:37 INFO - 'sessionrestore_no_auto_restore', 05:23:37 INFO - 'tabpaint']}, 05:23:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:37 INFO - 'ts_paint', 05:23:37 INFO - 'tpaint', 05:23:37 INFO - 'sessionrestore', 05:23:37 INFO - 'sessionrestore_no_auto_restore', 05:23:37 INFO - 'tabpaint']}, 05:23:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:37 INFO - 'ts_paint', 05:23:37 INFO - 'tpaint', 05:23:37 INFO - 'sessionrestore', 05:23:37 INFO - 'sessionrestore_no_auto_restore', 05:23:37 INFO - 'tabpaint']}, 05:23:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['a11yr', 05:23:37 INFO - 'ts_paint', 05:23:37 INFO - 'tpaint', 05:23:37 INFO - 'sessionrestore', 05:23:37 INFO - 'sessionrestore_no_auto_restore', 05:23:37 INFO - 'tabpaint']}, 05:23:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['a11yr', 05:23:37 INFO - 'ts_paint', 05:23:37 INFO - 'tpaint', 05:23:37 INFO - 'sessionrestore', 05:23:37 INFO - 'sessionrestore_no_auto_restore', 05:23:37 INFO - 'tabpaint']}, 05:23:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['tsvgx', 05:23:37 INFO - 'tsvgr_opacity', 05:23:37 INFO - 'tart', 05:23:37 INFO - 'tscrollx', 05:23:37 INFO - 'cart']}, 05:23:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:37 INFO - 'tsvgr_opacity', 05:23:37 INFO - 'tart', 05:23:37 INFO - 'tscrollx', 05:23:37 INFO - 'cart']}, 05:23:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:37 INFO - 'talos_options': ['--disable-e10s'], 05:23:37 INFO - 'tests': ['tp5o']}, 05:23:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:37 INFO - 'tests': ['tp5o']}, 05:23:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:37 INFO - 'talos_options': ['--disable-e10s', 05:23:37 INFO - '--xperf_path', 05:23:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:37 INFO - 'tests': ['tp5n']}, 05:23:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:37 INFO - 'talos_options': ['--xperf_path', 05:23:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:37 INFO - 'tests': ['tp5n']}}, 05:23:37 INFO - 'talos.zip': {'path': '', 05:23:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:37 INFO - Running post-action listener: _resource_record_post_action 05:23:37 INFO - ##### 05:23:37 INFO - ##### Finished populate-webroot step (success) 05:23:37 INFO - ##### 05:23:37 INFO - ##### 05:23:37 INFO - ##### Running create-virtualenv step. 05:23:37 INFO - ##### 05:23:37 INFO - Running pre-action listener: _resource_record_pre_action 05:23:37 INFO - Running main action method: create_virtualenv 05:23:37 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23:37 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:23:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:37 INFO - Using real prefix '/tools/python27' 05:23:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:23:38 INFO - Installing distribute.............................................................................................................................................................................................done. 05:23:40 INFO - Installing pip.................done. 05:23:40 INFO - Return code: 0 05:23:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:23:40 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:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:40 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:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:23:40 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:23:40 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:23:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:23:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:40 INFO - 'HOME': '/Users/cltbld', 05:23:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:40 INFO - 'LOGNAME': 'cltbld', 05:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:40 INFO - 'MOZ_NO_REMOTE': '1', 05:23:40 INFO - 'NO_EM_RESTART': '1', 05:23:40 INFO - 'PAGER': '/bin/cat', 05:23:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:40 INFO - 'PWD': '/builds/slave/test', 05:23:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:40 INFO - 'SHELL': '/bin/bash', 05:23:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:23:40 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:23:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:40 INFO - 'USER': 'cltbld', 05:23:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:40 INFO - 'XPC_FLAGS': '0x0', 05:23:40 INFO - 'XPC_SERVICE_NAME': '0', 05:23:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:41 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:23:41 INFO - Cleaning up... 05:23:41 INFO - Return code: 0 05:23:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:23:41 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:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:41 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:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:41 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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:23:41 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:23:41 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:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:41 INFO - 'HOME': '/Users/cltbld', 05:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:41 INFO - 'LOGNAME': 'cltbld', 05:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:41 INFO - 'MOZ_NO_REMOTE': '1', 05:23:41 INFO - 'NO_EM_RESTART': '1', 05:23:41 INFO - 'PAGER': '/bin/cat', 05:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:41 INFO - 'PWD': '/builds/slave/test', 05:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:41 INFO - 'SHELL': '/bin/bash', 05:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:23:41 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:41 INFO - 'USER': 'cltbld', 05:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:41 INFO - 'XPC_FLAGS': '0x0', 05:23:41 INFO - 'XPC_SERVICE_NAME': '0', 05:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:41 INFO - Downloading/unpacking psutil>=3.1.1 05:23: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:23: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:23: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:23: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:23: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:23: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:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:23:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:44 INFO - Installing collected packages: psutil 05:23:44 INFO - Running setup.py install for psutil 05:23:44 INFO - building 'psutil._psutil_osx' extension 05:23:44 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:23:44 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:23:44 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:23:44 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:23:45 INFO - building 'psutil._psutil_posix' extension 05:23:45 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:23:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:23:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:23:45 INFO - ^ 05:23:45 INFO - 1 warning generated. 05:23:45 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:23:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:45 INFO - Successfully installed psutil 05:23:45 INFO - Cleaning up... 05:23:45 INFO - Return code: 0 05:23:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:23:45 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:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:45 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:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:23:45 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:23:45 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:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:45 INFO - 'HOME': '/Users/cltbld', 05:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:45 INFO - 'LOGNAME': 'cltbld', 05:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:45 INFO - 'MOZ_NO_REMOTE': '1', 05:23:45 INFO - 'NO_EM_RESTART': '1', 05:23:45 INFO - 'PAGER': '/bin/cat', 05:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:45 INFO - 'PWD': '/builds/slave/test', 05:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:45 INFO - 'SHELL': '/bin/bash', 05:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:23:45 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:45 INFO - 'USER': 'cltbld', 05:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:45 INFO - 'XPC_FLAGS': '0x0', 05:23:45 INFO - 'XPC_SERVICE_NAME': '0', 05:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:45 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:23:45 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:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:23:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:23:48 INFO - Installing collected packages: mozsystemmonitor 05:23:48 INFO - Running setup.py install for mozsystemmonitor 05:23:48 INFO - Successfully installed mozsystemmonitor 05:23:48 INFO - Cleaning up... 05:23:48 INFO - Return code: 0 05:23:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:23:48 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:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:48 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:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:48 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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:23:48 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:23:48 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:23:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:23:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:48 INFO - 'HOME': '/Users/cltbld', 05:23:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:48 INFO - 'LOGNAME': 'cltbld', 05:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:48 INFO - 'MOZ_NO_REMOTE': '1', 05:23:48 INFO - 'NO_EM_RESTART': '1', 05:23:48 INFO - 'PAGER': '/bin/cat', 05:23:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:48 INFO - 'PWD': '/builds/slave/test', 05:23:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:48 INFO - 'SHELL': '/bin/bash', 05:23:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:23:48 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:23:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:48 INFO - 'USER': 'cltbld', 05:23:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:48 INFO - 'XPC_FLAGS': '0x0', 05:23:48 INFO - 'XPC_SERVICE_NAME': '0', 05:23:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:48 INFO - Downloading/unpacking blobuploader==1.2.4 05:23:48 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:23:48 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:23:48 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:23:48 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:23:48 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:23:48 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:23:51 INFO - Downloading blobuploader-1.2.4.tar.gz 05:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:23:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:23:51 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:23:51 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:23:51 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:23:51 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:23:51 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:23:51 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:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:23:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:23:51 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:23:51 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:23:51 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:23:51 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:23:51 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:23:51 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:23:52 INFO - Downloading docopt-0.6.1.tar.gz 05:23:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:23:52 INFO - Installing collected packages: blobuploader, requests, docopt 05:23:52 INFO - Running setup.py install for blobuploader 05:23:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:23:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:23:52 INFO - Running setup.py install for requests 05:23:53 INFO - Running setup.py install for docopt 05:23:53 INFO - Successfully installed blobuploader requests docopt 05:23:53 INFO - Cleaning up... 05:23:53 INFO - Return code: 0 05:23:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:53 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:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:53 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:23:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:53 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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:23:53 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:23:53 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:23:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:23:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:53 INFO - 'HOME': '/Users/cltbld', 05:23:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:53 INFO - 'LOGNAME': 'cltbld', 05:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:53 INFO - 'MOZ_NO_REMOTE': '1', 05:23:53 INFO - 'NO_EM_RESTART': '1', 05:23:53 INFO - 'PAGER': '/bin/cat', 05:23:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:53 INFO - 'PWD': '/builds/slave/test', 05:23:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:53 INFO - 'SHELL': '/bin/bash', 05:23:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:23:53 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:23:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:53 INFO - 'USER': 'cltbld', 05:23:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:53 INFO - 'XPC_FLAGS': '0x0', 05:23:53 INFO - 'XPC_SERVICE_NAME': '0', 05:23:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:53 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-vUUDdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:53 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-aBH5cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:53 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-CI5ALo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:53 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-jJn9nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:53 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-PznP_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ikveSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-nHoJq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-384diA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-gBKWAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-6AxYaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-JZ9obi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ZxXmO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Urh4F2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-sIx6x2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:54 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-8qLqPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:55 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-h8SPuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:55 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-bgggXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:23:55 INFO - Running setup.py install for manifestparser 05:23:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:23:55 INFO - Running setup.py install for mozcrash 05:23:55 INFO - Running setup.py install for mozdebug 05:23:55 INFO - Running setup.py install for mozdevice 05:23:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:23:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:23:55 INFO - Running setup.py install for mozfile 05:23:56 INFO - Running setup.py install for mozhttpd 05:23:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:23:56 INFO - Running setup.py install for mozinfo 05:23:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:23:56 INFO - Running setup.py install for mozInstall 05:23:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:23:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:23:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:23:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:23:56 INFO - Running setup.py install for mozleak 05:23:56 INFO - Running setup.py install for mozlog 05:23:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:23:56 INFO - Running setup.py install for moznetwork 05:23:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:23:56 INFO - Running setup.py install for mozprocess 05:23:57 INFO - Running setup.py install for mozprofile 05:23:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:23:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:23:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:23:57 INFO - Running setup.py install for mozrunner 05:23:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:23:57 INFO - Running setup.py install for mozscreenshot 05:23:57 INFO - Running setup.py install for moztest 05:23:57 INFO - Running setup.py install for mozversion 05:23:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:23:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:23:57 INFO - Cleaning up... 05:23:57 INFO - Return code: 0 05:23:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:57 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:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:57 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:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:57 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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:23:57 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:23:57 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:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:57 INFO - 'HOME': '/Users/cltbld', 05:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:57 INFO - 'LOGNAME': 'cltbld', 05:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:57 INFO - 'MOZ_NO_REMOTE': '1', 05:23:57 INFO - 'NO_EM_RESTART': '1', 05:23:57 INFO - 'PAGER': '/bin/cat', 05:23:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:57 INFO - 'PWD': '/builds/slave/test', 05:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:57 INFO - 'SHELL': '/bin/bash', 05:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:23:57 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:57 INFO - 'USER': 'cltbld', 05:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:57 INFO - 'XPC_FLAGS': '0x0', 05:23:57 INFO - 'XPC_SERVICE_NAME': '0', 05:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:58 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-50geez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:58 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:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:58 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-P15PSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:58 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:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:58 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-yOIaqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:58 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:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:58 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-t5N1Sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:58 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:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:58 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-1DssEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:58 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:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:58 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Rg98qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:58 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:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:58 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-QUbuX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:58 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:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:58 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-iyq0SL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:58 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:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-UtXpkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:59 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:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-SawCqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:59 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:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-EEoxSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:59 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:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-MMSgMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:59 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:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ufYgn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:59 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:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-rxUMy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:59 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:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-R_CmnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:59 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:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-e2WQ3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:59 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:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:59 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-zOYb05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:59 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:23:59 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:23:59 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:23:59 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:23:59 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:23:59 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:23:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:59 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:23:59 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:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:02 INFO - Downloading blessings-1.6.tar.gz 05:24:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:24:02 INFO - Installing collected packages: blessings 05:24:02 INFO - Running setup.py install for blessings 05:24:02 INFO - Successfully installed blessings 05:24:02 INFO - Cleaning up... 05:24:02 INFO - Return code: 0 05:24:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:24:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:03 INFO - Reading from file tmpfile_stdout 05:24:03 INFO - Current package versions: 05:24:03 INFO - blessings == 1.6 05:24:03 INFO - blobuploader == 1.2.4 05:24:03 INFO - docopt == 0.6.1 05:24:03 INFO - manifestparser == 1.1 05:24:03 INFO - mozInstall == 1.12 05:24:03 INFO - mozcrash == 0.17 05:24:03 INFO - mozdebug == 0.1 05:24:03 INFO - mozdevice == 0.48 05:24:03 INFO - mozfile == 1.2 05:24:03 INFO - mozhttpd == 0.7 05:24:03 INFO - mozinfo == 0.9 05:24:03 INFO - mozleak == 0.1 05:24:03 INFO - mozlog == 3.2 05:24:03 INFO - moznetwork == 0.27 05:24:03 INFO - mozprocess == 0.22 05:24:03 INFO - mozprofile == 0.28 05:24:03 INFO - mozrunner == 6.11 05:24:03 INFO - mozscreenshot == 0.1 05:24:03 INFO - mozsystemmonitor == 0.3 05:24:03 INFO - moztest == 0.7 05:24:03 INFO - mozversion == 1.4 05:24:03 INFO - psutil == 3.1.1 05:24:03 INFO - requests == 1.2.3 05:24:03 INFO - wsgiref == 0.1.2 05:24:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:03 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:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:03 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:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:03 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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:24:03 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:24:03 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:24:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:24:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:03 INFO - 'HOME': '/Users/cltbld', 05:24:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:03 INFO - 'LOGNAME': 'cltbld', 05:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:03 INFO - 'MOZ_NO_REMOTE': '1', 05:24:03 INFO - 'NO_EM_RESTART': '1', 05:24:03 INFO - 'PAGER': '/bin/cat', 05:24:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:03 INFO - 'PWD': '/builds/slave/test', 05:24:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:03 INFO - 'SHELL': '/bin/bash', 05:24:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:24:03 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:24:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:03 INFO - 'USER': 'cltbld', 05:24:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:03 INFO - 'XPC_FLAGS': '0x0', 05:24:03 INFO - 'XPC_SERVICE_NAME': '0', 05:24:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:03 INFO - Cleaning up... 05:24:03 INFO - Return code: 0 05:24:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:24:03 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:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:03 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:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:03 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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:24:03 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:24:03 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:24:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:24:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:03 INFO - 'HOME': '/Users/cltbld', 05:24:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:03 INFO - 'LOGNAME': 'cltbld', 05:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:03 INFO - 'MOZ_NO_REMOTE': '1', 05:24:03 INFO - 'NO_EM_RESTART': '1', 05:24:03 INFO - 'PAGER': '/bin/cat', 05:24:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:03 INFO - 'PWD': '/builds/slave/test', 05:24:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:03 INFO - 'SHELL': '/bin/bash', 05:24:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:24:03 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:24:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:03 INFO - 'USER': 'cltbld', 05:24:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:03 INFO - 'XPC_FLAGS': '0x0', 05:24:03 INFO - 'XPC_SERVICE_NAME': '0', 05:24:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:03 INFO - Downloading/unpacking jsonschema 05:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:03 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:24:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:24:05 INFO - Downloading/unpacking functools32 (from jsonschema) 05:24:05 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:24:05 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:24:05 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:24:05 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:24:05 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:24:05 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:24:06 INFO - Downloading functools32-3.2.3-2.tar.gz 05:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:24:06 INFO - warning: no files found matching '*.txt' 05:24:06 INFO - no previously-included directories found matching 'build' 05:24:06 INFO - no previously-included directories found matching 'dist' 05:24:06 INFO - no previously-included directories found matching '.git*' 05:24:06 INFO - Installing collected packages: jsonschema, functools32 05:24:06 INFO - Running setup.py install for functools32 05:24:06 INFO - warning: no files found matching '*.txt' 05:24:06 INFO - no previously-included directories found matching 'build' 05:24:06 INFO - no previously-included directories found matching 'dist' 05:24:06 INFO - no previously-included directories found matching '.git*' 05:24:06 INFO - Successfully installed jsonschema functools32 05:24:06 INFO - Cleaning up... 05:24:06 INFO - Return code: 0 05:24:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:24:06 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:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:06 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:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:06 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 0x101ef8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c04974e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, '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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:24:06 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:24:06 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:24:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:24:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:06 INFO - 'HOME': '/Users/cltbld', 05:24:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:06 INFO - 'LOGNAME': 'cltbld', 05:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:06 INFO - 'MOZ_NO_REMOTE': '1', 05:24:06 INFO - 'NO_EM_RESTART': '1', 05:24:06 INFO - 'PAGER': '/bin/cat', 05:24:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:06 INFO - 'PWD': '/builds/slave/test', 05:24:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:06 INFO - 'SHELL': '/bin/bash', 05:24:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:24:06 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:24:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:06 INFO - 'USER': 'cltbld', 05:24:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:06 INFO - 'XPC_FLAGS': '0x0', 05:24:06 INFO - 'XPC_SERVICE_NAME': '0', 05:24:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:07 INFO - Downloading/unpacking flake8 05:24:07 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:24:07 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:24:07 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:24:07 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:24:07 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:24:07 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:24:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:24:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:24:10 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:24:10 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:24:10 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:24:10 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:24:10 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:24:10 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:24:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:24:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:24:10 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:24:10 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:24:10 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:24:10 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:24:10 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:24:10 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:24:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:24:11 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:24:11 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:24:11 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:24:11 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:24:11 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:24:11 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:24:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:24:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:24:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:24:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:24:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:24:11 INFO - no previously-included directories found matching 'docs/_build' 05:24:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:24:11 INFO - Running setup.py install for pep8 05:24:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:24:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:24:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:24:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:24:12 INFO - no previously-included directories found matching 'docs/_build' 05:24:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:24:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:24:12 INFO - Cleaning up... 05:24:12 INFO - Return code: 0 05:24:12 INFO - Running post-action listener: _resource_record_post_action 05:24:12 INFO - Running post-action listener: _start_resource_monitoring 05:24:12 INFO - Starting resource monitoring. 05:24:12 INFO - ##### 05:24:12 INFO - ##### Finished create-virtualenv step (success) 05:24:12 INFO - ##### 05:24:12 INFO - ##### 05:24:12 INFO - ##### Running install step. 05:24:12 INFO - ##### 05:24:12 INFO - Running pre-action listener: _resource_record_pre_action 05:24:12 INFO - Running main action method: install 05:24:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:12 INFO - Reading from file tmpfile_stdout 05:24:12 INFO - Detecting whether we're running mozinstall >=1.0... 05:24:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:24:12 INFO - Reading from file tmpfile_stdout 05:24:12 INFO - Output received: 05:24:12 INFO - Usage: mozinstall [options] installer 05:24:12 INFO - Options: 05:24:12 INFO - -h, --help show this help message and exit 05:24:12 INFO - -d DEST, --destination=DEST 05:24:12 INFO - Directory to install application into. [default: 05:24:12 INFO - "/builds/slave/test"] 05:24:12 INFO - --app=APP Application being installed. [default: firefox] 05:24:12 INFO - mkdir: /builds/slave/test/build/application 05:24:12 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:24:12 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:24:47 INFO - Reading from file tmpfile_stdout 05:24:47 INFO - Output received: 05:24:47 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:24:47 INFO - Running post-action listener: _resource_record_post_action 05:24:47 INFO - ##### 05:24:47 INFO - ##### Finished install step (success) 05:24:47 INFO - ##### 05:24:47 INFO - ##### 05:24:47 INFO - ##### Running run-tests step. 05:24:47 INFO - ##### 05:24:47 INFO - Running pre-action listener: _resource_record_pre_action 05:24:47 INFO - Running main action method: run_tests 05:24:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:24:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:24:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:24:47 INFO - 2016-05-31 05:24:47.047 screenresolution[1930:11589] starting screenresolution argv=screenresolution get 05:24:47 INFO - 2016-05-31 05:24:47.050 screenresolution[1930:11589] Display 0: 1600x1200x32@60 05:24:47 INFO - 2016-05-31 05:24:47.056 screenresolution[1931:11591] starting screenresolution argv=screenresolution list 05:24:47 INFO - Available Modes on Display 0 05:24:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:24:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:24:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:24:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:24:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:24:47 INFO - Intel Iris: 05:24:47 INFO - Chipset Model: Intel Iris 05:24:47 INFO - Type: GPU 05:24:47 INFO - Bus: Built-In 05:24:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:24:47 INFO - Vendor: Intel (0x8086) 05:24:47 INFO - Device ID: 0x0a2e 05:24:47 INFO - Revision ID: 0x0009 05:24:47 INFO - Displays: 05:24:47 INFO - Display: 05:24:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:24:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:24:47 INFO - Display Serial Number: 1600x1200 60 05:24:47 INFO - Main Display: Yes 05:24:47 INFO - Mirror: Off 05:24:47 INFO - Online: Yes 05:24:47 INFO - Rotation: Supported 05:24:47 INFO - Return code: 0 05:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:47 INFO - Python 2.7.3 05:24:47 INFO - Return code: 0 05:24:47 INFO - grabbing minidump binary from tooltool 05:24:47 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:24:47 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 0x101d9cd30>, '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 0x101bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d93b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24:47 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:24:47 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:24:47 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:24:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:47 INFO - Return code: 0 05:24:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:24:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:24:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:24:48 INFO - Return code: 0 05:24:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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:24:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nH9k3pUT3P/Render', 05:24:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:48 INFO - 'HOME': '/Users/cltbld', 05:24:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:48 INFO - 'LOGNAME': 'cltbld', 05:24:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:48 INFO - 'MOZ_NO_REMOTE': '1', 05:24:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:48 INFO - 'NO_EM_RESTART': '1', 05:24:48 INFO - 'PAGER': '/bin/cat', 05:24:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:48 INFO - 'PWD': '/builds/slave/test', 05:24:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:48 INFO - 'SHELL': '/bin/bash', 05:24:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DKRSYdYD8/Listeners', 05:24:48 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:24:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:48 INFO - 'USER': 'cltbld', 05:24:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:48 INFO - 'XPC_FLAGS': '0x0', 05:24:48 INFO - 'XPC_SERVICE_NAME': '0', 05:24:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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:24:48 INFO - mozversion application_buildid: 20160531030258 05:24:48 INFO - mozversion application_changeset: 864cdd00360cdf62ea5132a457ee53a17f9e31aa 05:24:48 INFO - mozversion application_display_name: Nightly 05:24:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:48 INFO - mozversion application_name: Firefox 05:24:48 INFO - mozversion application_remotingname: firefox 05:24:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:24:48 INFO - mozversion application_vendor: Mozilla 05:24:48 INFO - mozversion application_version: 49.0a1 05:24:48 INFO - mozversion platform_buildid: 20160531030258 05:24:48 INFO - mozversion platform_changeset: 864cdd00360cdf62ea5132a457ee53a17f9e31aa 05:24:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:24:48 INFO - mozversion platform_version: 49.0a1 05:24:48 INFO - using testdate: 1464697488 05:24:48 INFO - actual date: 1464697488 05:24:48 INFO - starting webserver on 'localhost:50327' 05:24:48 INFO - SUITE-START | Running 2 tests 05:24:48 INFO - TEST-START | dromaeo_css 05:24:48 INFO - operating with platform_type : mac_ 05:24:48 INFO - Initialising browser for dromaeo_css test... 05:24:48 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpNIyGSs/profile http://localhost:50327/getInfo.html) 05:24:50 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 05:24:50 INFO - PROCESS | 1949 | colorDepth:24 05:24:50 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 05:24:50 INFO - PROCESS | 1949 | __metrics 05:24:51 INFO - PROCESS | 1949 | 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:24:51 ERROR - PROCESS | 1949 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:24:51 INFO - TEST-INFO | 1949: exit 0 05:24:51 INFO - Browser initialized. 05:24:51 INFO - Running cycle 1/1 for dromaeo_css test... 05:24:51 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpNIyGSs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:03 INFO - PROCESS | 1952 | RSS: Main: 241852416 05:25:03 INFO - PROCESS | 1952 | 05:25:54 INFO - PROCESS | 1952 | 1464697554587 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:28:06 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:50327/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50327/tests/dromaeo/cssquery-ext.html) 05:28:07 INFO - PROCESS | 1952 | RSS: Main: 331595776 05:28:07 INFO - PROCESS | 1952 | 05:31:10 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:50327/tests/dromaeo/cssquery-ext.html (next: http://localhost:50327/tests/dromaeo/cssquery-jquery.html) 05:31:11 INFO - PROCESS | 1952 | RSS: Main: 373432320 05:31:11 INFO - PROCESS | 1952 | 05:34:13 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:50327/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50327/tests/dromaeo/cssquery-mootools.html) 05:34:14 INFO - PROCESS | 1952 | RSS: Main: 371470336 05:34:14 INFO - PROCESS | 1952 | 05:37:16 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:50327/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50327/tests/dromaeo/cssquery-prototype.html) 05:37:17 INFO - PROCESS | 1952 | RSS: Main: 365830144 05:37:17 INFO - PROCESS | 1952 | 05:40:20 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:50327/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50327/tests/dromaeo/cssquery-yui.html) 05:40:21 INFO - PROCESS | 1952 | RSS: Main: 332136448 05:40:21 INFO - PROCESS | 1952 | 05:43:23 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:50327/tests/dromaeo/cssquery-yui.html (next: http://localhost:50327/tests/dromaeo/cssquery-dojo.html) 05:43:24 INFO - PROCESS | 1952 | RSS: Main: 392982528 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - PROCESS | 1952 | __start_tp_report 05:43:24 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 05:43:24 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 05:43:24 INFO - PROCESS | 1952 | |i|pagename|runs| 05:43:24 INFO - PROCESS | 1952 | |0;dojo.html;3408.386490431723;3434.2616337487443;3453.8273086345675;3458.533098031765;3495.825208739564;4174;5505.504504594585;5525.171224316355;5609.831705048845;5650.095984642463;5570;5641.830745077644;5662.5753068519925;5684.118961560955;5730.939776141417;5054.747262636872;5062.936783275517;5146.433892271866;5149.433562308143;5318.30861987943;5203.323567936163;5224.660397074197;5251.527362537371;5267.446918073604;5274.81538146165;5061.519155680205;5089.974550127263;5102.923456148161;5174.042802081627;5185.299984502081;4589.701669391512;4724.936889199937;5102.872428189325;5158.045761534091;5195.766190521398;5082.491750824925;5099.235114732783;5116.488351164891;5162;5192.662476938987;5448.45515448456;5465.125579907235;5527.723613819293;5565.888682226333;5646.830595082154;6101.981453397328;6103.511719062509;6105.368770830132;6117.969410152965;6124.663143527104;6156.230471191101;6201.534884883652;6221.253449586034;6227.87544249117;6235;5578.079616863253;5669.461401166883;5701.173329867229;5704.2299289595785;5751.597388182787;6258.561900666909;6269.874602508015;6270.090836828635;6293.779717709858;6295.559310848196;6039.245094363205;6090.064759283867;6114.882038094619;6119.755209791648;6128.153168891127;6110.358412366726;6115.362036198677;6137.8673257933415;6206.286277078194;6233.283172435137;6125.908111378333;6189.257289125334;6207.596506227081;6222.657753823492;6224;6148;6222.315545290015;6233.657148856766;6236.064590311399;6265.216847894013;4824.927626085611;4874.829380971649;4875;4916.778744956486;5007.223880298559;4443.755593442392;4459.732416055047;4481.641468682497;4486.977565112154;4496.392986946786;5574.080276754371;5576.498115169654;5579.13523403873;5583.357913839879;5586.525145362609;6146.631202127886;6153.815385538352;6161.106639537332;6173.351798061229;6215.968920155461;5642.294713160855;5650.463205995424;5659.462351076641;5680.77276908919;5685.459881311269;5276.92084618731;5400.486953739389;5410.215518749759;5417.51242388187;5423;6172.506199504029;6194.566380353423;6234.781782637586;6276.843078923063;6280.309165991828;6215;6236.282827474898;6238.126662267196;6244.625322480666;6245.250569931637;6195.287541932736;6200.689965501617;6257.655828929271;6259.217597800275;6279.560430769985;4901.607871370353;6157.199564056823;6159.4456498915315;6167.691615419121;6181.443670069715;6107.083937409424;6205.875882482415;6223.315435302023;6225.750969961332;6259.624422534663;5874.441928016831;5944.8811023780145;6027.095935609694;6047.969760151171;6060;6064.484518815773;6073.605215660968;6077.997130473423;6081.209442772588;6108.816735497942;5848.763969858457;5899.911501327398;5910.23166988306;5949.52403807703;5972.58191926561;5810.4189581041555;6038.426349496791;6064.06007068905;6089.086637004308;6130.846728831815;5920.822375328747;5967.9556077685365;5972.522198224045;5976.7908123215475;5983.012802887475 05:43:24 INFO - PROCESS | 1952 | |1;ext.html;486.3070125071773;492.56654144352984;493.0927094146769;507.5432111100011;549.9413628764629;31105.222369440777;32690.019299420997;32743.672563274362;32806.507902381454;32833.17917052075;42611.36082958758;43359.56640433595;43410;46194.84512887179;46362.30456543146;9468.715938521838;9684.41893486393;9696.17582505488;9723.756906077333;9745.707628771113;8540.615672294762;8764.649414023463;9475.668351607696;9597.520123993772;9598.616055357812;11569.016633586156;11845;11855.7628847423;12092.488438945133;12115.212511186744;9586.568604412783;11716.648500544998;11732.765344693058;11751.823722644167;11758.176927615114;8514.403991720583;11212.215144939857;11530.42347882611;11711.297322160697;11798.410079496076;40177.598224017675;40538.59461405378;40687.796561017305;40699.59300406987;40841.387379189335;3038.437888990544;3500.0899766061007;3529.6470352964498;3562.2341196642587;3641.1625326174862;30287.848560757277;31009.37981240385;31458.842705786323;31751.841240793878;31826.681733182602;3244.6917542833394;3259.820709860957;3269.264415506516;3290.07877794218;3353.0611428799975;495710;500978;501834;501919;503484.99999999633;48945.510544894096;57467.13799293014;58523.707381463675;58912.70543647254;58944.705276473345;35145.47281790775;35631.643683562834;35635.643643563235;58185.709071454374;58387;16925.661486770197;16962.91518542424;17065.82934170667;17111.315547377966;17128.057956812245;12926.289054101926;13027.674308142372;13028.739425211443;13029.674258143623;13030.54393096237;16840.83159168417;16978.575535611708;16987.575310617085;17617.942923424635;19516.31692890742;6657.23441804189;7907.446478746548;7915.683372665144;7993.640286187136;8006.359491240687;807.8828569857337;1002.2733518199393;1094.3324572011068;1132.5583022621183;1192.7435601345578;24475.776211189368;29915.551266731018;30144.698553014627;30797.846010770252;30890.382192356028;1025.333533203409;1106.8561087058793;1116.2465335898237;1144.3019757947645;1202.6452196602024;13895.513657022158;15454.381824726885;15512.767308490382;15536.378544858078;15670;652113.7394312999;662108;663270.0509492361;664625;664806;57310.85378292411;59045.40954590485;59207.81584368288;59253.70373148193;59382.40617593855;1408.021425109539;1420.6519402746394;1435.877950374209;1436.4038923846904;1440.5102265229873;23771.762282376956;26779.866100669373;27547.86226068857;29883;30512.542311864894;50936.74531627318;56851;58488;58561.82876342534;61427;576094;579545;586144;586653;588508;35933.46099808453;35987.92036238917;43606.56393436152;43936.780316098215;44056.898577535816;45232;48141.75929120332;48173.795655107504;48968.5103148964;49003.75498122487;38030.429543557424;40033.599664002984;40167.598324017556;40550.39174412445;40935.59064409437;37969;38163.80918095392;38833.834984950496;39507.40738888861;39823.40264895971;38129.618703813714;38650.8404747858;38785.2242955145;39040.80479597584;39050;33920;34368.65631343654;34833.825830870686;34880.82559587186;34968.30063398667;23754.099672026543;24379.26862194137;25224.747752522973;25428.618570721825;25740.970361185344 05:43:24 INFO - PROCESS | 1952 | |2;jquery.html;339.003330209185;357.21769325177877;385.65869205752904;388.18287504802373;400.39140506430226;15171.544853654394;15841;19977.400677979647;20290.188392464283;20325.695114573293;20152.59694806103;20823.375298741023;21138;21183.364499065014;21755.34733957984;5274.81538146165;5932.961731696957;6114.266288045441;6141.938580614204;6154.415330543591;5949.464548190662;6055.485283750864;6087.5998520340345;6099.237595300588;6455.419012288893;5779.422057794229;5962.314333851597;5986.700664966756;5995.800839832029;6088.36072212418;5848.941510584904;5915.290165180163;5925.05199168131;5928.199693041427;5959.986802243608;4901.779419926112;5200.479952004807;5221;5221.947780522184;5227.398849132361;25708.357291067685;25883.74116258851;26148.954041838304;26194.214173574823;26523.73476265251;2540.3395117269642;2811.00209425655;2865.6991015943445;3031.863566139529;3179.934721868177;15358.923205384013;15515.612109697235;15670.843291567166;16015.759763603439;16129.83870161295;1788.4277031349973;1835.128314050815;1849.1309084730156;1920.2798950393603;1975.693767466045;87030;87205;115955.42022289919;122223;123233.38383308025;10813.56745730162;10883.67348979532;11007;11066.39134847591;11197.328160310408;12376.07179461544;12807.61577152687;12929.741405172032;13616.523421680193;13857.861421385858;8582.87125693115;8638.049814520398;8716.346274029473;8819.735407937644;8820.647174113092;8714.041455439896;8737.169968852948;8743.825123497494;8840.8673869892;8873.73378798637;6484.1894763154605;6567;6596.637184954778;6605.768798092044;6610.504212184105;1594.4339759385482;1859.302761464451;1892.7350170976074;1895.914683839203;1917.2331067572804;932.6968735160964;954.5322791831952;1034.5758239121924;1042.1298215989734;1046.4767616191905;20441;21240.893795530923;21553.56892862134;21794.967930932828;21933.875230638616;888.8622263549161;939.2532936315646;1065.4193464561833;1096.5668560918552;1102.8841971593029;10092.243081768896;11393.430328483542;11739.191269063922;11912.404379780975;11971.64085077449;552772;561492;564557;576384;583304;114481;114679.42660286812;115000;115218.4239078816;117783.41108294403;1034.3121823987115;1049.160671462842;1050.3120456101267;1074.661481633283;1079.5735684404617;16138.19309034567;18864.056797160363;19716.873612860472;19752.604947901247;19870.50323741918;40066.79966600148;47318.76340618412;49340.75329623329;49364;50879;75226;79599.60200198962;80063.59968200122;80742;81794.18205818103;7913.9604301978125;8064.395170362363;8180.100188979327;8281.17188281167;8439.282660973886;9060.95469522648;9942.204623629948;11217.046551043253;12354.660604092382;12952.222866628032;9195.632174712937;10491.580336786443;11192.328460292409;11518.53925842957;11565.306081635128;11159.386233756897;11307;11448.141389395661;11468.82796758066;11474.885251147381;9790.167835734042;10804.67585972422;11501.194916355775;11689.590864319707;11886.58396956137;7574;8304.377171712267;8370.623321950405;8379.622916968628;8563.443376180529;8388.706395276136;10723.08853747409;10903.781924361627;11423;11462.853714628804 05:43:24 INFO - PROCESS | 1952 | |3;mootools.html;1363.911345762526;1413.455819509489;1565.8590726834582;1791.8745687801852;1983.7718662353825;8015.959920200398;8793.692220772275;9016.594253258603;9044;9169.816603667923;8772.868406973901;8959.372843900914;8968;8969.32730045246;8978.551072446384;2364.4916342986253;2402.1112188490197;2405.194259922919;2405.7955073818657;2408.3738228060743;2354.882255887199;2391.7249516305587;2400.8919598618018;2403.122860156039;2404.5311164322966;2255.3121298004103;2278.453171238899;2279.9854064941023;2308.076769292288;2317.385892738426;2348.178137651825;2369.18263199196;2369.218158007853;2377.429416939939;2386.129062892032;2328.6157783965627;2362.1968530699646;2369.4668699542635;2370.8458950168183;2371.2174982255815;8775.868361974575;8808.163224493663;8987.37088403812;8989.056149104315;9002.86495702565;5283.365996080495;5512.614117011811;5515.310586176728;5520.530754885839;5528.612997090206;3046.9542956855666;3359.1266270769534;3613.963860361389;3720.274546463441;3751.137238435148;1080.9621663241749;1104.9723756906062;1111.777644471101;1113.0372228022784;1117.8323251512256;11978.52085916571;12080.697982550506;12097.2136811107;12111.45498452563;12152.635420937386;4557.612602928788;4569.200389931749;4707.717536947725;4711.3639658646325;4712.458067322234;8147.83704325922;9298.302627302979;9454.33819632619;9470.668526601536;9505.334626576074;5285.867853303621;5345.064613692588;5357.919631205535;5361.008213480519;5365.597580181424;3989.501312335958;4460.687751857404;4612.630989520831;4941.604671626261;4963.6277279204205;3834;3847.1728578355787;3929.2730844793805;3950.3481925482547;4025.1547175092974;975.6731494949272;1131.3890499130541;1138.123644793521;1144.3991904250336;1157.2535714464123;660.531022973694;797.8523973065002;917.1745429113718;943.7923656795494;970.8786401699788;5892.440218179266;6045.697715114226;6085.1445060955775;6211.937880621226;6305.18032655761;477.49624146525053;477.50578151612916;478.2945155895097;499.2785425060825;644.9516286278548;3863.5943225961432;4103.835846566164;4136.44158038667;4169.895752606149;4179.811908464127;10578.153747700166;10862.945685271523;10919.12646988239;11028.172887033345;11039.83440248397;10974.506147223394;10984.725381865517;10988.670339889815;10995;10997.34015959029;8280.213379728915;8309.95845020771;8311.95844020776;8398.286145677564;8422.873656895152;5310.309659744208;5934.910976335272;6043.54673399488;6053.092036194606;6119.663418512025;10089.192864570963;10244.948775256076;10258.743531411774;10259.794804104025;10280.691579252634;10001.249906256915;10327.535260913126;10392.740181495523;10504.632337868137;10536.051755341749;6069.2716873975405;7101.502894797314;7110.32451917067;7184.281571842774;7184.604846733271;8287.58562071906;8392.958035209786;8400.201980811813;8427.188154546802;8494.193051660082;7228.277172282729;7234.74678386275;7296.671649775879;7371.299726526186;7407.296306850841;7401.370883474965;7579.204183560647;7642.312191902755;7713.112992005991;7755.495892766952;7246.927530724625;7269.781906542812;7313.085864266966;7315.780526584211;7354.411647068328;4058.6550143238155;5108.208227724708;5128.410232823273;5151.974240128776;5250.081235783646;5189.221616757516;5209.505097015777;5248.475152484721;5264.052470555337;5366.7048312343195 05:43:24 INFO - PROCESS | 1952 | |4;prototype.html;251.3377250943763;311.89239712299246;314.97322727568155;315.2009655523249;319.18925928142517;4346.109047645229;4809.9519004809945;5275.340582427197;5324.627276090675;5383.865403364918;4583.954160458403;5252.973735131329;5271.920921186185;5357.249985002093;5361.651492652984;3221.774475786696;3260.983695081533;3676.448532720087;3682.484452176684;3795.430685397199;3304.488588672602;3306.6528014558485;3594.982025089871;3859.614038596132;4094.549599544063;3897.2010737798682;3923.136909879822;3978.38335058065;3985.980070099645;4010.679145668354;3201.807891526516;3366.7474939379645;3974.463447434588;3999.6200360965663;4019.974906398879;2951.7933744637885;3110.4867696829997;3364.327134573071;3515.5781306243416;3542.9114272143147;4640.002399484127;4837.346958160639;5054.963732434842;5081.441041485434;5181.481851814826;3799.5820459749407;3844.500214972036;4595.103954728829;4604.332371806103;4608.078384323148;4662.277347011219;5192.818251361222;5528.8161176335125;5555.694436805974;5555.722213889289;3234.7735658503916;3881.7190327192243;3889.708271879592;3889.7358358533306;3900.5372985130575;4904.828331008398;5109.8722531937;5788.5369170466265;5832.387599302055;5870.412958704095;3014.894478693279;3563.821808909544;3606.314800187956;3889.3388124018984;3945.8421663133727;3812.904677383088;3885.4754608128105;3900.3369427197445;3901.4147877817986;3937.822797974098;4513.684042117086;4549.112922980054;4573.405457290531;4575.062112266977;4624.890026393641;4734.857954261378;4791.873909631234;5069.974650126799;5629;5663.037283661787;1169.9590514331958;1300.4343110746856;1321.808337791015;1334.6996925691642;1336.5789776220458;704.1022696062561;779.1818590479974;789.0452552411646;795.2524626850742;807.1444269074799;4174.290370636999;4724.574788269004;4925.950740492549;5140.768665410065;5142.9456961323585;5517.69652669099;5587.44125587438;5630.465105814941;5791.160281759206;5799.2750906136735;4097.467329247179;4373.322135069069;4662.580367766917;5202.635815492879;5214.426413094557;2744.8060093859235;2851.4867323881485;3017.0797906638686;3023.5011223148376;3032.1964679359985;4598.82729903879;5897.410258974154;5945.762169513171;5970.611910225822;5980.176046305832;5452.700101494456;5858.677772722456;5877.706114694248;5907.670774075798;5913.113033044991;5021.799128034838;5188.740562971836;5725.282415275401;5796.898589268026;5955.583109182402;5040.546350828443;5714.771409143587;5715.885682286248;5728.684922329312;5729.570282228933;4927.926081108714;5059.266406371129;5239.29269548606;5626.324841019104;5667.546596272371;5210.921836172384;5245.898361344161;5879.5007273145075;5933.970330148321;5958.463738263577;3165.398574270881;3427.1946092667704;3760.6051364607415;3766.832281992591;3808.3525800613406;4371;5236.020624325464;5255.973720131527;5671.376148623565;5676.807870347273;5254.080535906125;5378.784848606012;5483.2323474712775;5484.670919744828;5548.643070942022;4824.565789079;4900.730459824744;5494.203340515683;5504.990678402274;5542.2795036644975;5476.534494568006;5484.588655850747;5489.533389661923;5503.486541201201;5505.366882808528;4780.784864681167;5237.502437268454;5290.761915713725;5353.598480113929;5609.9158512621525;5255.527002569787;5496.175573663822;5501.092319767194;5510.614257001971;5592.804251851166 05:43:24 INFO - PROCESS | 1952 | |5;yui.html;700.8423104801419;733.3583114774573;739.4786675393848;746.4028776978416;841.6801615386153;14538.49115280964;15206.619834504128;15210.543683689479;15225.695486090286;15227.39090436384;13903.096298740575;13948.930255348734;13995.230262335568;14009.369578368973;14010.369533370997;702.0557350363753;729.0922801112622;871.2202578692098;885.6413152673161;902.1159263921361;888.094143973147;891.8394688955985;899.9055099214586;900.1763386501343;901.7475106970059;946.7775072857872;953.1755031897428;953.3517208298355;954.2556805691572;954.7279025477741;736.3740820302753;920.5581320965907;946.1815529566909;952.8046750416217;954.651552183455;704.802655256529;705.3651713457878;705.5026206524388;705.9505834591581;707.5683832861951;14603.196824174773;14648.780268295985;14664.413423463155;14667.339969701388;14684.26578671062;13372.665683357896;13474.461021559126;13511.527096551574;13588.93205533976;13865;12134.27194368332;12188.20776649515;12288.201266917711;12427.316497592628;12540.686482837911;4449.933251001237;4515.81936722534;4555.612772914306;4595.287730401793;4742.1227072991605;77291.45417091627;79170;79676;79739.6013019937;81236.59381703053;10748.785024299626;10859.945700271448;10946.616868409568;11116.38859862699;11285.43572821372;21237.36287911365;21261.893690531448;21262.362129136152;21282.042308096174;21333.040013199443;7872.212778722196;7930.881036784453;7949.046114466301;7988.680452781824;8198.672053117927;4342.761148136819;4407.184670835905;4424.269995450714;4531.886702832456;4545.0909818036525;3358.647342029101;3401.166714155048;3459.2735525539415;3461.826908654557;3465.1683595936793;675.864827034595;679.7247114918466;681.4820736240493;682.7985744205372;684.4387602166175;609.1715267236555;644.9097126402307;653.0889409274037;664.4086762781129;665.653859992801;8463.619137138843;8843.336749743796;8851.247643950337;8974.37179397449;9178.954105229432;559.454531831461;591.2107336705546;623.2707731953625;631.2456614345813;654.5581732330658;4251.574842515785;4319.870403887889;4332.328489084134;4428.31361139024;4471.5081341052455;93754;96973;97451.51274243584;100222.49666255013;100526;75747.62126189334;84159;84362;85072;85345.57327213325;71987.64006179935;73946.63026685048;75623;76171;77093.99999999888;6522.967385163044;8640.827183456422;8774.97481321874;8826.735197944072;8879.866801997847;39513.81458556248;43622.56377436185;44047.898802530195;44432.77783611061;48102.759486203744;63688;67021.66489167718;67603.66198168977;67648;67940.6602966982;12875.227486350848;12906.967442604757;13132.934335328262;13139.934300328438;13679.79480307816;14515.274236288355;14636.121832689647;14773.852261477678;15189.772153417487;15266.542003739905;8420.142769854525;12714.554990575285;13300.600981970556;13612.183269003892;13829.239391833144;12577.559785407468;12950.870491294967;13028.022898282856;13030.54393096237;13063.150895191782;12935.741285174432;13099.541516047273;13501.459941602227;13558.728825423634;13602.91176705881;9782.92387837323;10762.19283553721;11247.7750444989;11582.6525204244;11876.346800926032;7989.393992254693;9835.60657573689;10328.225383096147;11185.72035699114;11313.547458101584 05:43:24 INFO - PROCESS | 1952 | __end_tp_report 05:43:24 INFO - PROCESS | 1952 | __start_cc_report 05:43:24 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,1311 05:43:24 INFO - PROCESS | 1952 | __end_cc_report 05:43:24 INFO - PROCESS | 1952 | __startTimestamp1464698604114__endTimestamp 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - PROCESS | 1952 | ------- Summary: start ------- 05:43:24 INFO - PROCESS | 1952 | Number of tests: 6 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - PROCESS | 1952 | [#0] dojo.html Cycles:165 Average:5680.53 Median:5944.88 stddev:639.50 (10.8%) stddev-sans-first:616.12 05:43:24 INFO - PROCESS | 1952 | Values: 3408.4 3434.3 3453.8 3458.5 3495.8 4174.0 5505.5 5525.2 5609.8 5650.1 5570.0 5641.8 5662.6 5684.1 5730.9 5054.7 5062.9 5146.4 5149.4 5318.3 5203.3 5224.7 5251.5 5267.4 5274.8 5061.5 5090.0 5102.9 5174.0 5185.3 4589.7 4724.9 5102.9 5158.0 5195.8 5082.5 5099.2 5116.5 5162.0 5192.7 5448.5 5465.1 5527.7 5565.9 5646.8 6102.0 6103.5 6105.4 6118.0 6124.7 6156.2 6201.5 6221.3 6227.9 6235.0 5578.1 5669.5 5701.2 5704.2 5751.6 6258.6 6269.9 6270.1 6293.8 6295.6 6039.2 6090.1 6114.9 6119.8 6128.2 6110.4 6115.4 6137.9 6206.3 6233.3 6125.9 6189.3 6207.6 6222.7 6224.0 6148.0 6222.3 6233.7 6236.1 6265.2 4824.9 4874.8 4875.0 4916.8 5007.2 4443.8 4459.7 4481.6 4487.0 4496.4 5574.1 5576.5 5579.1 5583.4 5586.5 6146.6 6153.8 6161.1 6173.4 6216.0 5642.3 5650.5 5659.5 5680.8 5685.5 5276.9 5400.5 5410.2 5417.5 5423.0 6172.5 6194.6 6234.8 6276.8 6280.3 6215.0 6236.3 6238.1 6244.6 6245.3 6195.3 6200.7 6257.7 6259.2 6279.6 4901.6 6157.2 6159.4 6167.7 6181.4 6107.1 6205.9 6223.3 6225.8 6259.6 5874.4 5944.9 6027.1 6048.0 6060.0 6064.5 6073.6 6078.0 6081.2 6108.8 5848.8 5899.9 5910.2 5949.5 5972.6 5810.4 6038.4 6064.1 6089.1 6130.8 5920.8 5968.0 5972.5 5976.8 5983.0 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - PROCESS | 1952 | [#1] ext.html Cycles:180 Average:71346.46 Median:19814.54 stddev:156495.83 (789.8%) stddev-sans-first:156844.40 05:43:24 INFO - PROCESS | 1952 | Values: 486.3 492.6 493.1 507.5 549.9 31105.2 32690.0 32743.7 32806.5 32833.2 42611.4 43359.6 43410.0 46194.8 46362.3 9468.7 9684.4 9696.2 9723.8 9745.7 8540.6 8764.6 9475.7 9597.5 9598.6 11569.0 11845.0 11855.8 12092.5 12115.2 9586.6 11716.6 11732.8 11751.8 11758.2 8514.4 11212.2 11530.4 11711.3 11798.4 40177.6 40538.6 40687.8 40699.6 40841.4 3038.4 3500.1 3529.6 3562.2 3641.2 30287.8 31009.4 31458.8 31751.8 31826.7 3244.7 3259.8 3269.3 3290.1 3353.1 495710.0 500978.0 501834.0 501919.0 503485.0 48945.5 57467.1 58523.7 58912.7 58944.7 35145.5 35631.6 35635.6 58185.7 58387.0 16925.7 16962.9 17065.8 17111.3 17128.1 12926.3 13027.7 13028.7 13029.7 13030.5 16840.8 16978.6 16987.6 17617.9 19516.3 6657.2 7907.4 7915.7 7993.6 8006.4 807.9 1002.3 1094.3 1132.6 1192.7 24475.8 29915.6 30144.7 30797.8 30890.4 1025.3 1106.9 1116.2 1144.3 1202.6 13895.5 15454.4 15512.8 15536.4 15670.0 652113.7 662108.0 663270.1 664625.0 664806.0 57310.9 59045.4 59207.8 59253.7 59382.4 1408.0 1420.7 1435.9 1436.4 1440.5 23771.8 26779.9 27547.9 29883.0 30512.5 50936.7 56851.0 58488.0 58561.8 61427.0 576094.0 579545.0 586144.0 586653.0 588508.0 35933.5 35987.9 43606.6 43936.8 44056.9 45232.0 48141.8 48173.8 48968.5 49003.8 38030.4 40033.6 40167.6 40550.4 40935.6 37969.0 38163.8 38833.8 39507.4 39823.4 38129.6 38650.8 38785.2 39040.8 39050.0 33920.0 34368.7 34833.8 34880.8 34968.3 23754.1 24379.3 25224.7 25428.6 25741.0 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - PROCESS | 1952 | [#2] jquery.html Cycles:180 Average:33574.91 Median:25796.05 stddev:94479.21 (366.3%) stddev-sans-first:94711.29 05:43:24 INFO - PROCESS | 1952 | Values: 339.0 357.2 385.7 388.2 400.4 15171.5 15841.0 19977.4 20290.2 20325.7 20152.6 20823.4 21138.0 21183.4 21755.3 5274.8 5933.0 6114.3 6141.9 6154.4 5949.5 6055.5 6087.6 6099.2 6455.4 5779.4 5962.3 5986.7 5995.8 6088.4 5848.9 5915.3 5925.1 5928.2 5960.0 4901.8 5200.5 5221.0 5221.9 5227.4 25708.4 25883.7 26149.0 26194.2 26523.7 2540.3 2811.0 2865.7 3031.9 3179.9 15358.9 15515.6 15670.8 16015.8 16129.8 1788.4 1835.1 1849.1 1920.3 1975.7 87030.0 87205.0 115955.4 122223.0 123233.4 10813.6 10883.7 11007.0 11066.4 11197.3 12376.1 12807.6 12929.7 13616.5 13857.9 8582.9 8638.0 8716.3 8819.7 8820.6 8714.0 8737.2 8743.8 8840.9 8873.7 6484.2 6567.0 6596.6 6605.8 6610.5 1594.4 1859.3 1892.7 1895.9 1917.2 932.7 954.5 1034.6 1042.1 1046.5 20441.0 21240.9 21553.6 21795.0 21933.9 888.9 939.3 1065.4 1096.6 1102.9 10092.2 11393.4 11739.2 11912.4 11971.6 552772.0 561492.0 564557.0 576384.0 583304.0 114481.0 114679.4 115000.0 115218.4 117783.4 1034.3 1049.2 1050.3 1074.7 1079.6 16138.2 18864.1 19716.9 19752.6 19870.5 40066.8 47318.8 49340.8 49364.0 50879.0 75226.0 79599.6 80063.6 80742.0 81794.2 7914.0 8064.4 8180.1 8281.2 8439.3 9061.0 9942.2 11217.0 12354.7 12952.2 9195.6 10491.6 11192.3 11518.5 11565.3 11159.4 11307.0 11448.1 11468.8 11474.9 9790.2 10804.7 11501.2 11689.6 11886.6 7574.0 8304.4 8370.6 8379.6 8563.4 8388.7 10723.1 10903.8 11423.0 11462.9 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - PROCESS | 1952 | [#3] mootools.html Cycles:180 Average:5751.24 Median:2709.95 stddev:3279.74 (121.0%) stddev-sans-first:3272.36 05:43:24 INFO - PROCESS | 1952 | Values: 1363.9 1413.5 1565.9 1791.9 1983.8 8016.0 8793.7 9016.6 9044.0 9169.8 8772.9 8959.4 8968.0 8969.3 8978.6 2364.5 2402.1 2405.2 2405.8 2408.4 2354.9 2391.7 2400.9 2403.1 2404.5 2255.3 2278.5 2280.0 2308.1 2317.4 2348.2 2369.2 2369.2 2377.4 2386.1 2328.6 2362.2 2369.5 2370.8 2371.2 8775.9 8808.2 8987.4 8989.1 9002.9 5283.4 5512.6 5515.3 5520.5 5528.6 3047.0 3359.1 3614.0 3720.3 3751.1 1081.0 1105.0 1111.8 1113.0 1117.8 11978.5 12080.7 12097.2 12111.5 12152.6 4557.6 4569.2 4707.7 4711.4 4712.5 8147.8 9298.3 9454.3 9470.7 9505.3 5285.9 5345.1 5357.9 5361.0 5365.6 3989.5 4460.7 4612.6 4941.6 4963.6 3834.0 3847.2 3929.3 3950.3 4025.2 975.7 1131.4 1138.1 1144.4 1157.3 660.5 797.9 917.2 943.8 970.9 5892.4 6045.7 6085.1 6211.9 6305.2 477.5 477.5 478.3 499.3 645.0 3863.6 4103.8 4136.4 4169.9 4179.8 10578.2 10862.9 10919.1 11028.2 11039.8 10974.5 10984.7 10988.7 10995.0 10997.3 8280.2 8310.0 8312.0 8398.3 8422.9 5310.3 5934.9 6043.5 6053.1 6119.7 10089.2 10244.9 10258.7 10259.8 10280.7 10001.2 10327.5 10392.7 10504.6 10536.1 6069.3 7101.5 7110.3 7184.3 7184.6 8287.6 8393.0 8400.2 8427.2 8494.2 7228.3 7234.7 7296.7 7371.3 7407.3 7401.4 7579.2 7642.3 7713.1 7755.5 7246.9 7269.8 7313.1 7315.8 7354.4 4058.7 5108.2 5128.4 5152.0 5250.1 5189.2 5209.5 5248.5 5264.1 5366.7 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - PROCESS | 1952 | [#4] prototype.html Cycles:180 Average:4420.86 Median:4974.86 stddev:1398.00 (28.1%) stddev-sans-first:1366.45 05:43:24 INFO - PROCESS | 1952 | Values: 251.3 311.9 315.0 315.2 319.2 4346.1 4810.0 5275.3 5324.6 5383.9 4584.0 5253.0 5271.9 5357.2 5361.7 3221.8 3261.0 3676.4 3682.5 3795.4 3304.5 3306.7 3595.0 3859.6 4094.5 3897.2 3923.1 3978.4 3986.0 4010.7 3201.8 3366.7 3974.5 3999.6 4020.0 2951.8 3110.5 3364.3 3515.6 3542.9 4640.0 4837.3 5055.0 5081.4 5181.5 3799.6 3844.5 4595.1 4604.3 4608.1 4662.3 5192.8 5528.8 5555.7 5555.7 3234.8 3881.7 3889.7 3889.7 3900.5 4904.8 5109.9 5788.5 5832.4 5870.4 3014.9 3563.8 3606.3 3889.3 3945.8 3812.9 3885.5 3900.3 3901.4 3937.8 4513.7 4549.1 4573.4 4575.1 4624.9 4734.9 4791.9 5070.0 5629.0 5663.0 1170.0 1300.4 1321.8 1334.7 1336.6 704.1 779.2 789.0 795.3 807.1 4174.3 4724.6 4926.0 5140.8 5142.9 5517.7 5587.4 5630.5 5791.2 5799.3 4097.5 4373.3 4662.6 5202.6 5214.4 2744.8 2851.5 3017.1 3023.5 3032.2 4598.8 5897.4 5945.8 5970.6 5980.2 5452.7 5858.7 5877.7 5907.7 5913.1 5021.8 5188.7 5725.3 5796.9 5955.6 5040.5 5714.8 5715.9 5728.7 5729.6 4927.9 5059.3 5239.3 5626.3 5667.5 5210.9 5245.9 5879.5 5934.0 5958.5 3165.4 3427.2 3760.6 3766.8 3808.4 4371.0 5236.0 5256.0 5671.4 5676.8 5254.1 5378.8 5483.2 5484.7 5548.6 4824.6 4900.7 5494.2 5505.0 5542.3 5476.5 5484.6 5489.5 5503.5 5505.4 4780.8 5237.5 5290.8 5353.6 5609.9 5255.5 5496.2 5501.1 5510.6 5592.8 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - PROCESS | 1952 | [#5] yui.html Cycles:180 Average:19013.77 Median:4668.71 stddev:26286.92 (563.0%) stddev-sans-first:26324.70 05:43:24 INFO - PROCESS | 1952 | Values: 700.8 733.4 739.5 746.4 841.7 14538.5 15206.6 15210.5 15225.7 15227.4 13903.1 13948.9 13995.2 14009.4 14010.4 702.1 729.1 871.2 885.6 902.1 888.1 891.8 899.9 900.2 901.7 946.8 953.2 953.4 954.3 954.7 736.4 920.6 946.2 952.8 954.7 704.8 705.4 705.5 706.0 707.6 14603.2 14648.8 14664.4 14667.3 14684.3 13372.7 13474.5 13511.5 13588.9 13865.0 12134.3 12188.2 12288.2 12427.3 12540.7 4449.9 4515.8 4555.6 4595.3 4742.1 77291.5 79170.0 79676.0 79739.6 81236.6 10748.8 10859.9 10946.6 11116.4 11285.4 21237.4 21261.9 21262.4 21282.0 21333.0 7872.2 7930.9 7949.0 7988.7 8198.7 4342.8 4407.2 4424.3 4531.9 4545.1 3358.6 3401.2 3459.3 3461.8 3465.2 675.9 679.7 681.5 682.8 684.4 609.2 644.9 653.1 664.4 665.7 8463.6 8843.3 8851.2 8974.4 9179.0 559.5 591.2 623.3 631.2 654.6 4251.6 4319.9 4332.3 4428.3 4471.5 93754.0 96973.0 97451.5 100222.5 100526.0 75747.6 84159.0 84362.0 85072.0 85345.6 71987.6 73946.6 75623.0 76171.0 77094.0 6523.0 8640.8 8775.0 8826.7 8879.9 39513.8 43622.6 44047.9 44432.8 48102.8 63688.0 67021.7 67603.7 67648.0 67940.7 12875.2 12907.0 13132.9 13139.9 13679.8 14515.3 14636.1 14773.9 15189.8 15266.5 8420.1 12714.6 13300.6 13612.2 13829.2 12577.6 12950.9 13028.0 13030.5 13063.2 12935.7 13099.5 13501.5 13558.7 13602.9 9782.9 10762.2 11247.8 11582.7 11876.3 7989.4 9835.6 10328.2 11185.7 11313.5 05:43:24 INFO - PROCESS | 1952 | -------- Summary: end -------- 05:43:24 INFO - PROCESS | 1952 | 05:43:24 INFO - TEST-INFO | 1952: exit 0 05:43:24 INFO - TEST-OK | dromaeo_css | took 1115989ms 05:43:24 INFO - TEST-START | kraken 05:43:24 INFO - operating with platform_type : mac_ 05:43:24 INFO - Initialising browser for kraken test... 05:43:24 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpqWYO5x/profile http://localhost:50327/getInfo.html) 05:43:26 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 05:43:26 INFO - PROCESS | 1957 | colorDepth:24 05:43:26 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 05:43:26 INFO - PROCESS | 1957 | __metrics 05:43:27 INFO - PROCESS | 1957 | 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:43:27 ERROR - PROCESS | 1957 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:43:27 INFO - TEST-INFO | 1957: exit 0 05:43:27 INFO - Browser initialized. 05:43:27 INFO - Running cycle 1/1 for kraken test... 05:43:27 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpqWYO5x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:39 INFO - PROCESS | 1959 | RSS: Main: 241709056 05:43:39 INFO - PROCESS | 1959 | 05:43:42 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50327/tests/kraken/driver.html?testName=audio-beat-detection) 05:43:43 INFO - PROCESS | 1959 | RSS: Main: 291753984 05:43:43 INFO - PROCESS | 1959 | 05:43:45 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50327/tests/kraken/driver.html?testName=audio-dft) 05:43:46 INFO - PROCESS | 1959 | RSS: Main: 301436928 05:43:46 INFO - PROCESS | 1959 | 05:43:50 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50327/tests/kraken/driver.html?testName=audio-fft) 05:43:50 INFO - PROCESS | 1959 | RSS: Main: 388386816 05:43:50 INFO - PROCESS | 1959 | 05:43:52 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50327/tests/kraken/driver.html?testName=audio-oscillator) 05:43:53 INFO - PROCESS | 1959 | RSS: Main: 288407552 05:43:53 INFO - PROCESS | 1959 | 05:43:55 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50327/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:43:56 INFO - PROCESS | 1959 | RSS: Main: 292966400 05:43:56 INFO - PROCESS | 1959 | 05:43:59 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50327/tests/kraken/driver.html?testName=imaging-darkroom) 05:43:59 INFO - PROCESS | 1959 | RSS: Main: 328187904 05:43:59 INFO - PROCESS | 1959 | 05:44:02 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50327/tests/kraken/driver.html?testName=imaging-desaturate) 05:44:03 INFO - PROCESS | 1959 | RSS: Main: 329531392 05:44:03 INFO - PROCESS | 1959 | 05:44:05 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50327/tests/kraken/driver.html?testName=json-parse-financial) 05:44:06 INFO - PROCESS | 1959 | RSS: Main: 330715136 05:44:06 INFO - PROCESS | 1959 | 05:44:08 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50327/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:44:08 INFO - PROCESS | 1959 | RSS: Main: 310849536 05:44:08 INFO - PROCESS | 1959 | 05:44:10 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50327/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:44:11 INFO - PROCESS | 1959 | RSS: Main: 318607360 05:44:11 INFO - PROCESS | 1959 | 05:44:13 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50327/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:44:14 INFO - PROCESS | 1959 | RSS: Main: 322555904 05:44:14 INFO - PROCESS | 1959 | 05:44:17 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50327/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:44:17 INFO - PROCESS | 1959 | RSS: Main: 323661824 05:44:17 INFO - PROCESS | 1959 | 05:44:20 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50327/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:44:21 INFO - PROCESS | 1959 | RSS: Main: 324603904 05:44:21 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:50327/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50327/tests/kraken/driver.html?testName=ai-astar) 05:44:23 INFO - PROCESS | 1959 | RSS: Main: 368427008 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | __start_tp_report 05:44:23 INFO - PROCESS | 1959 | _x_x_mozilla_page_load 05:44:23 INFO - PROCESS | 1959 | _x_x_mozilla_page_load_details 05:44:23 INFO - PROCESS | 1959 | |i|pagename|runs| 05:44:23 INFO - PROCESS | 1959 | |0;ai-astar;112;106;113;112;112;115;113;112;116;113 05:44:23 INFO - PROCESS | 1959 | |1;audio-beat-detection;153;118;127;164;118;133;157;105;164;143 05:44:23 INFO - PROCESS | 1959 | |2;audio-dft;185;185;182;182;186;180;185;182;184;182 05:44:23 INFO - PROCESS | 1959 | |3;audio-fft;83;75;59;106;85;80;68;64;81;56 05:44:23 INFO - PROCESS | 1959 | |4;audio-oscillator;86;90;94;71;71;66;93;70;68;103 05:44:23 INFO - PROCESS | 1959 | |5;imaging-gaussian-blur;87;92;113;119;134;84;89;85;88;84 05:44:23 INFO - PROCESS | 1959 | |6;imaging-darkroom;94;94;93;94;93;94;94;102;94;94 05:44:23 INFO - PROCESS | 1959 | |7;imaging-desaturate;84;85;85;86;84;84;83;85;84;87 05:44:23 INFO - PROCESS | 1959 | |8;json-parse-financial;56;54;55;56;55;55;55;55;56;56 05:44:23 INFO - PROCESS | 1959 | |9;json-stringify-tinderbox;66;71;77;80;76;71;68;69;69;70 05:44:23 INFO - PROCESS | 1959 | |10;stanford-crypto-aes;87;87;84;77;99;97;105;109;121;85 05:44:23 INFO - PROCESS | 1959 | |11;stanford-crypto-ccm;137;142;141;138;138;141;143;144;161;141 05:44:23 INFO - PROCESS | 1959 | |12;stanford-crypto-pbkdf2;206;225;146;207;224;171;167;169;223;148 05:44:23 INFO - PROCESS | 1959 | |13;stanford-crypto-sha256-iterative;68;66;69;66;66;66;67;66;66;65 05:44:23 INFO - PROCESS | 1959 | __end_tp_report 05:44:23 INFO - PROCESS | 1959 | __start_cc_report 05:44:23 INFO - PROCESS | 1959 | _x_x_mozilla_cycle_collect,1992 05:44:23 INFO - PROCESS | 1959 | __end_cc_report 05:44:23 INFO - PROCESS | 1959 | __startTimestamp1464698663579__endTimestamp 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | ------- Summary: start ------- 05:44:23 INFO - PROCESS | 1959 | Number of tests: 14 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:2.63 (2.3%) stddev-sans-first:2.79 05:44:23 INFO - PROCESS | 1959 | Values: 112.0 106.0 113.0 112.0 112.0 115.0 113.0 112.0 116.0 113.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:148.00 stddev:21.08 (14.2%) stddev-sans-first:21.66 05:44:23 INFO - PROCESS | 1959 | Values: 153.0 118.0 127.0 164.0 118.0 133.0 157.0 105.0 164.0 143.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.50 stddev:1.95 (1.1%) stddev-sans-first:1.96 05:44:23 INFO - PROCESS | 1959 | Values: 185.0 185.0 182.0 182.0 186.0 180.0 185.0 182.0 184.0 182.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#3] audio-fft Cycles:10 Average:75.70 Median:77.50 stddev:14.79 (19.1%) stddev-sans-first:15.45 05:44:23 INFO - PROCESS | 1959 | Values: 83.0 75.0 59.0 106.0 85.0 80.0 68.0 64.0 81.0 56.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:78.50 stddev:13.41 (17.1%) stddev-sans-first:14.11 05:44:23 INFO - PROCESS | 1959 | Values: 86.0 90.0 94.0 71.0 71.0 66.0 93.0 70.0 68.0 103.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:86.00 stddev:17.82 (20.7%) stddev-sans-first:18.49 05:44:23 INFO - PROCESS | 1959 | Values: 87.0 92.0 113.0 119.0 134.0 84.0 89.0 85.0 88.0 84.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.00 stddev:2.63 (2.8%) stddev-sans-first:2.78 05:44:23 INFO - PROCESS | 1959 | Values: 94.0 94.0 93.0 94.0 93.0 94.0 94.0 102.0 94.0 94.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.16 (1.4%) stddev-sans-first:1.20 05:44:23 INFO - PROCESS | 1959 | Values: 84.0 85.0 85.0 86.0 84.0 84.0 83.0 85.0 84.0 87.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:0.67 (1.2%) stddev-sans-first:0.67 05:44:23 INFO - PROCESS | 1959 | Values: 56.0 54.0 55.0 56.0 55.0 55.0 55.0 55.0 56.0 56.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.00 stddev:4.47 (6.3%) stddev-sans-first:4.24 05:44:23 INFO - PROCESS | 1959 | Values: 66.0 71.0 77.0 80.0 76.0 71.0 68.0 69.0 69.0 70.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#10] stanford-crypto-aes Cycles:10 Average:95.10 Median:86.00 stddev:13.60 (15.8%) stddev-sans-first:14.11 05:44:23 INFO - PROCESS | 1959 | Values: 87.0 87.0 84.0 77.0 99.0 97.0 105.0 109.0 121.0 85.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:141.50 stddev:6.85 (4.8%) stddev-sans-first:6.96 05:44:23 INFO - PROCESS | 1959 | Values: 137.0 142.0 141.0 138.0 138.0 141.0 143.0 144.0 161.0 141.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.60 Median:206.50 stddev:31.67 (15.3%) stddev-sans-first:32.96 05:44:23 INFO - PROCESS | 1959 | Values: 206.0 225.0 146.0 207.0 224.0 171.0 167.0 169.0 223.0 148.0 05:44:23 INFO - PROCESS | 1959 | 05:44:23 INFO - PROCESS | 1959 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.00 stddev:1.18 (1.8%) stddev-sans-first:1.12 05:44:23 INFO - PROCESS | 1959 | Values: 68.0 66.0 69.0 66.0 66.0 66.0 67.0 66.0 66.0 65.0 05:44:23 INFO - PROCESS | 1959 | -------- Summary: end -------- 05:44:23 INFO - PROCESS | 1959 | 05:44:24 INFO - TEST-INFO | 1959: exit 0 05:44:24 INFO - TEST-OK | kraken | took 59675ms 05:44:24 INFO - SUITE-END | took 1175s 05:44:24 INFO - Completed test suite (00:19:36) 05:44:24 INFO - javascript benchmark 05:44:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.386490431723, 3434.2616337487443, 3453.8273086345675, 3458.533098031765, 3495.825208739564, 4174.0, 5505.504504594585, 5525.171224316355, 5609.831705048845, 5650.095984642463, 5570.0, 5641.830745077644, 5662.5753068519925, 5684.118961560955, 5730.939776141417, 5054.747262636872, 5062.936783275517, 5146.433892271866, 5149.433562308143, 5318.30861987943, 5203.323567936163, 5224.660397074197, 5251.527362537371, 5267.446918073604, 5274.81538146165, 5061.519155680205, 5089.974550127263, 5102.923456148161, 5174.042802081627, 5185.299984502081, 4589.701669391512, 4724.936889199937, 5102.872428189325, 5158.045761534091, 5195.766190521398, 5082.491750824925, 5099.235114732783, 5116.488351164891, 5162.0, 5192.662476938987, 5448.45515448456, 5465.125579907235, 5527.723613819293, 5565.888682226333, 5646.830595082154, 6101.981453397328, 6103.511719062509, 6105.368770830132, 6117.969410152965, 6124.663143527104, 6156.230471191101, 6201.534884883652, 6221.253449586034, 6227.87544249117, 6235.0, 5578.079616863253, 5669.461401166883, 5701.173329867229, 5704.2299289595785, 5751.597388182787, 6258.561900666909, 6269.874602508015, 6270.090836828635, 6293.779717709858, 6295.559310848196, 6039.245094363205, 6090.064759283867, 6114.882038094619, 6119.755209791648, 6128.153168891127, 6110.358412366726, 6115.362036198677, 6137.8673257933415, 6206.286277078194, 6233.283172435137, 6125.908111378333, 6189.257289125334, 6207.596506227081, 6222.657753823492, 6224.0, 6148.0, 6222.315545290015, 6233.657148856766, 6236.064590311399, 6265.216847894013, 4824.927626085611, 4874.829380971649, 4875.0, 4916.778744956486, 5007.223880298559, 4443.755593442392, 4459.732416055047, 4481.641468682497, 4486.977565112154, 4496.392986946786, 5574.080276754371, 5576.498115169654, 5579.13523403873, 5583.357913839879, 5586.525145362609, 6146.631202127886, 6153.815385538352, 6161.106639537332, 6173.351798061229, 6215.968920155461, 5642.294713160855, 5650.463205995424, 5659.462351076641, 5680.77276908919, 5685.459881311269, 5276.92084618731, 5400.486953739389, 5410.215518749759, 5417.51242388187, 5423.0, 6172.506199504029, 6194.566380353423, 6234.781782637586, 6276.843078923063, 6280.309165991828, 6215.0, 6236.282827474898, 6238.126662267196, 6244.625322480666, 6245.250569931637, 6195.287541932736, 6200.689965501617, 6257.655828929271, 6259.217597800275, 6279.560430769985, 4901.607871370353, 6157.199564056823, 6159.4456498915315, 6167.691615419121, 6181.443670069715, 6107.083937409424, 6205.875882482415, 6223.315435302023, 6225.750969961332, 6259.624422534663, 5874.441928016831, 5944.8811023780145, 6027.095935609694, 6047.969760151171, 6060.0, 6064.484518815773, 6073.605215660968, 6077.997130473423, 6081.209442772588, 6108.816735497942, 5848.763969858457, 5899.911501327398, 5910.23166988306, 5949.52403807703, 5972.58191926561, 5810.4189581041555, 6038.426349496791, 6064.06007068905, 6089.086637004308, 6130.846728831815, 5920.822375328747, 5967.9556077685365, 5972.522198224045, 5976.7908123215475, 5983.012802887475], "value": 5641.1633595885005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.3070125071773, 492.56654144352984, 493.0927094146769, 507.5432111100011, 549.9413628764629, 31105.222369440777, 32690.019299420997, 32743.672563274362, 32806.507902381454, 32833.17917052075, 42611.36082958758, 43359.56640433595, 43410.0, 46194.84512887179, 46362.30456543146, 9468.715938521838, 9684.41893486393, 9696.17582505488, 9723.756906077333, 9745.707628771113, 8540.615672294762, 8764.649414023463, 9475.668351607696, 9597.520123993772, 9598.616055357812, 11569.016633586156, 11845.0, 11855.7628847423, 12092.488438945133, 12115.212511186744, 9586.568604412783, 11716.648500544998, 11732.765344693058, 11751.823722644167, 11758.176927615114, 8514.403991720583, 11212.215144939857, 11530.42347882611, 11711.297322160697, 11798.410079496076, 40177.598224017675, 40538.59461405378, 40687.796561017305, 40699.59300406987, 40841.387379189335, 3038.437888990544, 3500.0899766061007, 3529.6470352964498, 3562.2341196642587, 3641.1625326174862, 30287.848560757277, 31009.37981240385, 31458.842705786323, 31751.841240793878, 31826.681733182602, 3244.6917542833394, 3259.820709860957, 3269.264415506516, 3290.07877794218, 3353.0611428799975, 495710.0, 500978.0, 501834.0, 501919.0, 503484.99999999633, 48945.510544894096, 57467.13799293014, 58523.707381463675, 58912.70543647254, 58944.705276473345, 35145.47281790775, 35631.643683562834, 35635.643643563235, 58185.709071454374, 58387.0, 16925.661486770197, 16962.91518542424, 17065.82934170667, 17111.315547377966, 17128.057956812245, 12926.289054101926, 13027.674308142372, 13028.739425211443, 13029.674258143623, 13030.54393096237, 16840.83159168417, 16978.575535611708, 16987.575310617085, 17617.942923424635, 19516.31692890742, 6657.23441804189, 7907.446478746548, 7915.683372665144, 7993.640286187136, 8006.359491240687, 807.8828569857337, 1002.2733518199393, 1094.3324572011068, 1132.5583022621183, 1192.7435601345578, 24475.776211189368, 29915.551266731018, 30144.698553014627, 30797.846010770252, 30890.382192356028, 1025.333533203409, 1106.8561087058793, 1116.2465335898237, 1144.3019757947645, 1202.6452196602024, 13895.513657022158, 15454.381824726885, 15512.767308490382, 15536.378544858078, 15670.0, 652113.7394312999, 662108.0, 663270.0509492361, 664625.0, 664806.0, 57310.85378292411, 59045.40954590485, 59207.81584368288, 59253.70373148193, 59382.40617593855, 1408.021425109539, 1420.6519402746394, 1435.877950374209, 1436.4038923846904, 1440.5102265229873, 23771.762282376956, 26779.866100669373, 27547.86226068857, 29883.0, 30512.542311864894, 50936.74531627318, 56851.0, 58488.0, 58561.82876342534, 61427.0, 576094.0, 579545.0, 586144.0, 586653.0, 588508.0, 35933.46099808453, 35987.92036238917, 43606.56393436152, 43936.780316098215, 44056.898577535816, 45232.0, 48141.75929120332, 48173.795655107504, 48968.5103148964, 49003.75498122487, 38030.429543557424, 40033.599664002984, 40167.598324017556, 40550.39174412445, 40935.59064409437, 37969.0, 38163.80918095392, 38833.834984950496, 39507.40738888861, 39823.40264895971, 38129.618703813714, 38650.8404747858, 38785.2242955145, 39040.80479597584, 39050.0, 33920.0, 34368.65631343654, 34833.825830870686, 34880.82559587186, 34968.30063398667, 23754.099672026543, 24379.26862194137, 25224.747752522973, 25428.618570721825, 25740.970361185344], "value": 20404.828159843986, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.003330209185, 357.21769325177877, 385.65869205752904, 388.18287504802373, 400.39140506430226, 15171.544853654394, 15841.0, 19977.400677979647, 20290.188392464283, 20325.695114573293, 20152.59694806103, 20823.375298741023, 21138.0, 21183.364499065014, 21755.34733957984, 5274.81538146165, 5932.961731696957, 6114.266288045441, 6141.938580614204, 6154.415330543591, 5949.464548190662, 6055.485283750864, 6087.5998520340345, 6099.237595300588, 6455.419012288893, 5779.422057794229, 5962.314333851597, 5986.700664966756, 5995.800839832029, 6088.36072212418, 5848.941510584904, 5915.290165180163, 5925.05199168131, 5928.199693041427, 5959.986802243608, 4901.779419926112, 5200.479952004807, 5221.0, 5221.947780522184, 5227.398849132361, 25708.357291067685, 25883.74116258851, 26148.954041838304, 26194.214173574823, 26523.73476265251, 2540.3395117269642, 2811.00209425655, 2865.6991015943445, 3031.863566139529, 3179.934721868177, 15358.923205384013, 15515.612109697235, 15670.843291567166, 16015.759763603439, 16129.83870161295, 1788.4277031349973, 1835.128314050815, 1849.1309084730156, 1920.2798950393603, 1975.693767466045, 87030.0, 87205.0, 115955.42022289919, 122223.0, 123233.38383308025, 10813.56745730162, 10883.67348979532, 11007.0, 11066.39134847591, 11197.328160310408, 12376.07179461544, 12807.61577152687, 12929.741405172032, 13616.523421680193, 13857.861421385858, 8582.87125693115, 8638.049814520398, 8716.346274029473, 8819.735407937644, 8820.647174113092, 8714.041455439896, 8737.169968852948, 8743.825123497494, 8840.8673869892, 8873.73378798637, 6484.1894763154605, 6567.0, 6596.637184954778, 6605.768798092044, 6610.504212184105, 1594.4339759385482, 1859.302761464451, 1892.7350170976074, 1895.914683839203, 1917.2331067572804, 932.6968735160964, 954.5322791831952, 1034.5758239121924, 1042.1298215989734, 1046.4767616191905, 20441.0, 21240.893795530923, 21553.56892862134, 21794.967930932828, 21933.875230638616, 888.8622263549161, 939.2532936315646, 1065.4193464561833, 1096.5668560918552, 1102.8841971593029, 10092.243081768896, 11393.430328483542, 11739.191269063922, 11912.404379780975, 11971.64085077449, 552772.0, 561492.0, 564557.0, 576384.0, 583304.0, 114481.0, 114679.42660286812, 115000.0, 115218.4239078816, 117783.41108294403, 1034.3121823987115, 1049.160671462842, 1050.3120456101267, 1074.661481633283, 1079.5735684404617, 16138.19309034567, 18864.056797160363, 19716.873612860472, 19752.604947901247, 19870.50323741918, 40066.79966600148, 47318.76340618412, 49340.75329623329, 49364.0, 50879.0, 75226.0, 79599.60200198962, 80063.59968200122, 80742.0, 81794.18205818103, 7913.9604301978125, 8064.395170362363, 8180.100188979327, 8281.17188281167, 8439.282660973886, 9060.95469522648, 9942.204623629948, 11217.046551043253, 12354.660604092382, 12952.222866628032, 9195.632174712937, 10491.580336786443, 11192.328460292409, 11518.53925842957, 11565.306081635128, 11159.386233756897, 11307.0, 11448.141389395661, 11468.82796758066, 11474.885251147381, 9790.167835734042, 10804.67585972422, 11501.194916355775, 11689.590864319707, 11886.58396956137, 7574.0, 8304.377171712267, 8370.623321950405, 8379.622916968628, 8563.443376180529, 8388.706395276136, 10723.08853747409, 10903.781924361627, 11423.0, 11462.853714628804], "value": 9749.812134268426, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.911345762526, 1413.455819509489, 1565.8590726834582, 1791.8745687801852, 1983.7718662353825, 8015.959920200398, 8793.692220772275, 9016.594253258603, 9044.0, 9169.816603667923, 8772.868406973901, 8959.372843900914, 8968.0, 8969.32730045246, 8978.551072446384, 2364.4916342986253, 2402.1112188490197, 2405.194259922919, 2405.7955073818657, 2408.3738228060743, 2354.882255887199, 2391.7249516305587, 2400.8919598618018, 2403.122860156039, 2404.5311164322966, 2255.3121298004103, 2278.453171238899, 2279.9854064941023, 2308.076769292288, 2317.385892738426, 2348.178137651825, 2369.18263199196, 2369.218158007853, 2377.429416939939, 2386.129062892032, 2328.6157783965627, 2362.1968530699646, 2369.4668699542635, 2370.8458950168183, 2371.2174982255815, 8775.868361974575, 8808.163224493663, 8987.37088403812, 8989.056149104315, 9002.86495702565, 5283.365996080495, 5512.614117011811, 5515.310586176728, 5520.530754885839, 5528.612997090206, 3046.9542956855666, 3359.1266270769534, 3613.963860361389, 3720.274546463441, 3751.137238435148, 1080.9621663241749, 1104.9723756906062, 1111.777644471101, 1113.0372228022784, 1117.8323251512256, 11978.52085916571, 12080.697982550506, 12097.2136811107, 12111.45498452563, 12152.635420937386, 4557.612602928788, 4569.200389931749, 4707.717536947725, 4711.3639658646325, 4712.458067322234, 8147.83704325922, 9298.302627302979, 9454.33819632619, 9470.668526601536, 9505.334626576074, 5285.867853303621, 5345.064613692588, 5357.919631205535, 5361.008213480519, 5365.597580181424, 3989.501312335958, 4460.687751857404, 4612.630989520831, 4941.604671626261, 4963.6277279204205, 3834.0, 3847.1728578355787, 3929.2730844793805, 3950.3481925482547, 4025.1547175092974, 975.6731494949272, 1131.3890499130541, 1138.123644793521, 1144.3991904250336, 1157.2535714464123, 660.531022973694, 797.8523973065002, 917.1745429113718, 943.7923656795494, 970.8786401699788, 5892.440218179266, 6045.697715114226, 6085.1445060955775, 6211.937880621226, 6305.18032655761, 477.49624146525053, 477.50578151612916, 478.2945155895097, 499.2785425060825, 644.9516286278548, 3863.5943225961432, 4103.835846566164, 4136.44158038667, 4169.895752606149, 4179.811908464127, 10578.153747700166, 10862.945685271523, 10919.12646988239, 11028.172887033345, 11039.83440248397, 10974.506147223394, 10984.725381865517, 10988.670339889815, 10995.0, 10997.34015959029, 8280.213379728915, 8309.95845020771, 8311.95844020776, 8398.286145677564, 8422.873656895152, 5310.309659744208, 5934.910976335272, 6043.54673399488, 6053.092036194606, 6119.663418512025, 10089.192864570963, 10244.948775256076, 10258.743531411774, 10259.794804104025, 10280.691579252634, 10001.249906256915, 10327.535260913126, 10392.740181495523, 10504.632337868137, 10536.051755341749, 6069.2716873975405, 7101.502894797314, 7110.32451917067, 7184.281571842774, 7184.604846733271, 8287.58562071906, 8392.958035209786, 8400.201980811813, 8427.188154546802, 8494.193051660082, 7228.277172282729, 7234.74678386275, 7296.671649775879, 7371.299726526186, 7407.296306850841, 7401.370883474965, 7579.204183560647, 7642.312191902755, 7713.112992005991, 7755.495892766952, 7246.927530724625, 7269.781906542812, 7313.085864266966, 7315.780526584211, 7354.411647068328, 4058.6550143238155, 5108.208227724708, 5128.410232823273, 5151.974240128776, 5250.081235783646, 5189.221616757516, 5209.505097015777, 5248.475152484721, 5264.052470555337, 5366.7048312343195], "value": 4528.801834494243, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.3377250943763, 311.89239712299246, 314.97322727568155, 315.2009655523249, 319.18925928142517, 4346.109047645229, 4809.9519004809945, 5275.340582427197, 5324.627276090675, 5383.865403364918, 4583.954160458403, 5252.973735131329, 5271.920921186185, 5357.249985002093, 5361.651492652984, 3221.774475786696, 3260.983695081533, 3676.448532720087, 3682.484452176684, 3795.430685397199, 3304.488588672602, 3306.6528014558485, 3594.982025089871, 3859.614038596132, 4094.549599544063, 3897.2010737798682, 3923.136909879822, 3978.38335058065, 3985.980070099645, 4010.679145668354, 3201.807891526516, 3366.7474939379645, 3974.463447434588, 3999.6200360965663, 4019.974906398879, 2951.7933744637885, 3110.4867696829997, 3364.327134573071, 3515.5781306243416, 3542.9114272143147, 4640.002399484127, 4837.346958160639, 5054.963732434842, 5081.441041485434, 5181.481851814826, 3799.5820459749407, 3844.500214972036, 4595.103954728829, 4604.332371806103, 4608.078384323148, 4662.277347011219, 5192.818251361222, 5528.8161176335125, 5555.694436805974, 5555.722213889289, 3234.7735658503916, 3881.7190327192243, 3889.708271879592, 3889.7358358533306, 3900.5372985130575, 4904.828331008398, 5109.8722531937, 5788.5369170466265, 5832.387599302055, 5870.412958704095, 3014.894478693279, 3563.821808909544, 3606.314800187956, 3889.3388124018984, 3945.8421663133727, 3812.904677383088, 3885.4754608128105, 3900.3369427197445, 3901.4147877817986, 3937.822797974098, 4513.684042117086, 4549.112922980054, 4573.405457290531, 4575.062112266977, 4624.890026393641, 4734.857954261378, 4791.873909631234, 5069.974650126799, 5629.0, 5663.037283661787, 1169.9590514331958, 1300.4343110746856, 1321.808337791015, 1334.6996925691642, 1336.5789776220458, 704.1022696062561, 779.1818590479974, 789.0452552411646, 795.2524626850742, 807.1444269074799, 4174.290370636999, 4724.574788269004, 4925.950740492549, 5140.768665410065, 5142.9456961323585, 5517.69652669099, 5587.44125587438, 5630.465105814941, 5791.160281759206, 5799.2750906136735, 4097.467329247179, 4373.322135069069, 4662.580367766917, 5202.635815492879, 5214.426413094557, 2744.8060093859235, 2851.4867323881485, 3017.0797906638686, 3023.5011223148376, 3032.1964679359985, 4598.82729903879, 5897.410258974154, 5945.762169513171, 5970.611910225822, 5980.176046305832, 5452.700101494456, 5858.677772722456, 5877.706114694248, 5907.670774075798, 5913.113033044991, 5021.799128034838, 5188.740562971836, 5725.282415275401, 5796.898589268026, 5955.583109182402, 5040.546350828443, 5714.771409143587, 5715.885682286248, 5728.684922329312, 5729.570282228933, 4927.926081108714, 5059.266406371129, 5239.29269548606, 5626.324841019104, 5667.546596272371, 5210.921836172384, 5245.898361344161, 5879.5007273145075, 5933.970330148321, 5958.463738263577, 3165.398574270881, 3427.1946092667704, 3760.6051364607415, 3766.832281992591, 3808.3525800613406, 4371.0, 5236.020624325464, 5255.973720131527, 5671.376148623565, 5676.807870347273, 5254.080535906125, 5378.784848606012, 5483.2323474712775, 5484.670919744828, 5548.643070942022, 4824.565789079, 4900.730459824744, 5494.203340515683, 5504.990678402274, 5542.2795036644975, 5476.534494568006, 5484.588655850747, 5489.533389661923, 5503.486541201201, 5505.366882808528, 4780.784864681167, 5237.502437268454, 5290.761915713725, 5353.598480113929, 5609.9158512621525, 5255.527002569787, 5496.175573663822, 5501.092319767194, 5510.614257001971, 5592.804251851166], "value": 3972.820280282121, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.8423104801419, 733.3583114774573, 739.4786675393848, 746.4028776978416, 841.6801615386153, 14538.49115280964, 15206.619834504128, 15210.543683689479, 15225.695486090286, 15227.39090436384, 13903.096298740575, 13948.930255348734, 13995.230262335568, 14009.369578368973, 14010.369533370997, 702.0557350363753, 729.0922801112622, 871.2202578692098, 885.6413152673161, 902.1159263921361, 888.094143973147, 891.8394688955985, 899.9055099214586, 900.1763386501343, 901.7475106970059, 946.7775072857872, 953.1755031897428, 953.3517208298355, 954.2556805691572, 954.7279025477741, 736.3740820302753, 920.5581320965907, 946.1815529566909, 952.8046750416217, 954.651552183455, 704.802655256529, 705.3651713457878, 705.5026206524388, 705.9505834591581, 707.5683832861951, 14603.196824174773, 14648.780268295985, 14664.413423463155, 14667.339969701388, 14684.26578671062, 13372.665683357896, 13474.461021559126, 13511.527096551574, 13588.93205533976, 13865.0, 12134.27194368332, 12188.20776649515, 12288.201266917711, 12427.316497592628, 12540.686482837911, 4449.933251001237, 4515.81936722534, 4555.612772914306, 4595.287730401793, 4742.1227072991605, 77291.45417091627, 79170.0, 79676.0, 79739.6013019937, 81236.59381703053, 10748.785024299626, 10859.945700271448, 10946.616868409568, 11116.38859862699, 11285.43572821372, 21237.36287911365, 21261.893690531448, 21262.362129136152, 21282.042308096174, 21333.040013199443, 7872.212778722196, 7930.881036784453, 7949.046114466301, 7988.680452781824, 8198.672053117927, 4342.761148136819, 4407.184670835905, 4424.269995450714, 4531.886702832456, 4545.0909818036525, 3358.647342029101, 3401.166714155048, 3459.2735525539415, 3461.826908654557, 3465.1683595936793, 675.864827034595, 679.7247114918466, 681.4820736240493, 682.7985744205372, 684.4387602166175, 609.1715267236555, 644.9097126402307, 653.0889409274037, 664.4086762781129, 665.653859992801, 8463.619137138843, 8843.336749743796, 8851.247643950337, 8974.37179397449, 9178.954105229432, 559.454531831461, 591.2107336705546, 623.2707731953625, 631.2456614345813, 654.5581732330658, 4251.574842515785, 4319.870403887889, 4332.328489084134, 4428.31361139024, 4471.5081341052455, 93754.0, 96973.0, 97451.51274243584, 100222.49666255013, 100526.0, 75747.62126189334, 84159.0, 84362.0, 85072.0, 85345.57327213325, 71987.64006179935, 73946.63026685048, 75623.0, 76171.0, 77093.99999999888, 6522.967385163044, 8640.827183456422, 8774.97481321874, 8826.735197944072, 8879.866801997847, 39513.81458556248, 43622.56377436185, 44047.898802530195, 44432.77783611061, 48102.759486203744, 63688.0, 67021.66489167718, 67603.66198168977, 67648.0, 67940.6602966982, 12875.227486350848, 12906.967442604757, 13132.934335328262, 13139.934300328438, 13679.79480307816, 14515.274236288355, 14636.121832689647, 14773.852261477678, 15189.772153417487, 15266.542003739905, 8420.142769854525, 12714.554990575285, 13300.600981970556, 13612.183269003892, 13829.239391833144, 12577.559785407468, 12950.870491294967, 13028.022898282856, 13030.54393096237, 13063.150895191782, 12935.741285174432, 13099.541516047273, 13501.459941602227, 13558.728825423634, 13602.91176705881, 9782.92387837323, 10762.19283553721, 11247.7750444989, 11582.6525204244, 11876.346800926032, 7989.393992254693, 9835.60657573689, 10328.225383096147, 11185.72035699114, 11313.547458101584], "value": 7316.902608110117, "unit": "score"}], "name": "dromaeo_css", "value": 7270.976277821668, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 113.0, 112.0, 112.0, 115.0, 113.0, 112.0, 116.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 118.0, 127.0, 164.0, 118.0, 133.0, 157.0, 105.0, 164.0, 143.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 182.0, 182.0, 186.0, 180.0, 185.0, 182.0, 184.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 59.0, 106.0, 85.0, 80.0, 68.0, 64.0, 81.0, 56.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 94.0, 71.0, 71.0, 66.0, 93.0, 70.0, 68.0, 103.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 113.0, 119.0, 134.0, 84.0, 89.0, 85.0, 88.0, 84.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0, 102.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 86.0, 84.0, 84.0, 83.0, 85.0, 84.0, 87.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 56.0, 55.0, 55.0, 55.0, 55.0, 56.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 77.0, 80.0, 76.0, 71.0, 68.0, 69.0, 69.0, 70.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 77.0, 99.0, 97.0, 105.0, 109.0, 121.0, 85.0], "value": 95.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 142.0, 141.0, 138.0, 138.0, 141.0, 143.0, 144.0, 161.0, 141.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 225.0, 146.0, 207.0, 224.0, 171.0, 167.0, 169.0, 223.0, 148.0], "value": 188.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1487.3999999999999, "alertThreshold": 2.0}]} 05:44:24 INFO - Return code: 0 05:44:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:44:24 ERROR - # TBPL SUCCESS # 05:44:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:44:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:44:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:44:24 INFO - 2016-05-31 05:44:24.927 screenresolution[1963:13688] starting screenresolution argv=screenresolution get 05:44:24 INFO - 2016-05-31 05:44:24.930 screenresolution[1963:13688] Display 0: 1600x1200x32@60 05:44:24 INFO - 2016-05-31 05:44:24.935 screenresolution[1964:13691] starting screenresolution argv=screenresolution list 05:44:24 INFO - Available Modes on Display 0 05:44:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:44:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:44:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:44:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:44:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:44:25 INFO - Intel Iris: 05:44:25 INFO - Chipset Model: Intel Iris 05:44:25 INFO - Type: GPU 05:44:25 INFO - Bus: Built-In 05:44:25 INFO - VRAM (Dynamic, Max): 1536 MB 05:44:25 INFO - Vendor: Intel (0x8086) 05:44:25 INFO - Device ID: 0x0a2e 05:44:25 INFO - Revision ID: 0x0009 05:44:25 INFO - Displays: 05:44:25 INFO - Display: 05:44:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:44:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:44:25 INFO - Display Serial Number: 1600x1200 60 05:44:25 INFO - Main Display: Yes 05:44:25 INFO - Mirror: Off 05:44:25 INFO - Online: Yes 05:44:25 INFO - Rotation: Supported 05:44:25 INFO - Return code: 0 05:44:25 INFO - Running post-action listener: _resource_record_post_action 05:44:25 INFO - ##### 05:44:25 INFO - ##### Finished run-tests step (success) 05:44:25 INFO - ##### 05:44:25 INFO - Running post-run listener: _resource_record_post_run 05:44:25 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1142784; Write bytes: 697883648; Read time: 14; Write time: 1976 05:44:25 INFO - TinderboxPrint: CPU usage
53.6% 05:44:25 INFO - TinderboxPrint: I/O read bytes / time
1,142,784 / 14 05:44:25 INFO - TinderboxPrint: I/O write bytes / time
697,883,648 / 1,976 05:44:25 INFO - TinderboxPrint: CPU idle
2,248.9 (46.4%) 05:44:25 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 05:44:25 INFO - TinderboxPrint: CPU user
1,633.1 (33.7%) 05:44:25 INFO - TinderboxPrint: Swap in / out
400,719,872 / 0 05:44:25 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 382441984; Write bytes: 377106944; Read time: 28781; Write time: 770 05:44:25 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1142784; Write bytes: 295672320; Read time: 14; Write time: 1157 05:44:25 INFO - Running post-run listener: _upload_blobber_files 05:44:25 INFO - Blob upload gear active. 05:44:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:44:25 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:44:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:44:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:44:26 INFO - (blobuploader) - INFO - Open directory for files ... 05:44:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:44:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:44:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:27 INFO - (blobuploader) - INFO - Done attempting. 05:44:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:44:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:44:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:28 INFO - (blobuploader) - INFO - Done attempting. 05:44:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:44:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:44:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:31 INFO - (blobuploader) - INFO - Done attempting. 05:44:31 INFO - (blobuploader) - INFO - Iteration through files over. 05:44:31 INFO - Return code: 0 05:44:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:44:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:44:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c775e19dbd68af0d575d685f809b7bb7c4b8325947f645fb715c7e9bbd28957edfd9d5915f220f996ea79ef9e1567e782d516d7d61b49b88122c48577041ee7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e217a488865c8dd6ae1856af7dee818b2e61a67054da6518af8a4ece0dd1862c8a0452bf02f82d82df3b37895cb39cdf4186b8648b7ab8cc6c68f7013c6bfba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f6d3b069b8e572fb9e9c8e4142761354a6f42efe334da3121f61d412ada20e1c7c7452674514b9483fbac81f14aad3dcf589ebbbd05636768a87e511587e75"} 05:44:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:44:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:44:31 INFO - Contents: 05:44:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c775e19dbd68af0d575d685f809b7bb7c4b8325947f645fb715c7e9bbd28957edfd9d5915f220f996ea79ef9e1567e782d516d7d61b49b88122c48577041ee7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e217a488865c8dd6ae1856af7dee818b2e61a67054da6518af8a4ece0dd1862c8a0452bf02f82d82df3b37895cb39cdf4186b8648b7ab8cc6c68f7013c6bfba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f6d3b069b8e572fb9e9c8e4142761354a6f42efe334da3121f61d412ada20e1c7c7452674514b9483fbac81f14aad3dcf589ebbbd05636768a87e511587e75"} 05:44:31 INFO - Running post-run listener: copy_logs_to_upload_dir 05:44:31 INFO - Copying logs to upload dir... 05:44:31 INFO - mkdir: /builds/slave/test/build/upload/logs 05:44:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.452715 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-31 05:44:31.883881) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 05:44:31.886947) ========= 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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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/Firefox/sha512/8c775e19dbd68af0d575d685f809b7bb7c4b8325947f645fb715c7e9bbd28957edfd9d5915f220f996ea79ef9e1567e782d516d7d61b49b88122c48577041ee7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e217a488865c8dd6ae1856af7dee818b2e61a67054da6518af8a4ece0dd1862c8a0452bf02f82d82df3b37895cb39cdf4186b8648b7ab8cc6c68f7013c6bfba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f6d3b069b8e572fb9e9c8e4142761354a6f42efe334da3121f61d412ada20e1c7c7452674514b9483fbac81f14aad3dcf589ebbbd05636768a87e511587e75"} build_url:https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011034 build_url: 'https://queue.taskcluster.net/v1/task/KmHdxbF8QHeaYVN3xs5IvQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c775e19dbd68af0d575d685f809b7bb7c4b8325947f645fb715c7e9bbd28957edfd9d5915f220f996ea79ef9e1567e782d516d7d61b49b88122c48577041ee7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e217a488865c8dd6ae1856af7dee818b2e61a67054da6518af8a4ece0dd1862c8a0452bf02f82d82df3b37895cb39cdf4186b8648b7ab8cc6c68f7013c6bfba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f6d3b069b8e572fb9e9c8e4142761354a6f42efe334da3121f61d412ada20e1c7c7452674514b9483fbac81f14aad3dcf589ebbbd05636768a87e511587e75"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 05:44:31.925493) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:44:31.926217) ========= 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.nH9k3pUT3P/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.4DKRSYdYD8/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005195 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:44:31.980302) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 05:44:31.981027) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 05:44:31.981790) ========= ========= Total master_lag: 0.18 =========