builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0311 starttime: 1464056481.16 results: success (0) buildid: 20160523171639 builduid: 2df6f63a3d754fefa021eb2c65da7322 revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:21.165199) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:21.165684) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:21.165964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:21.191507) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:21.191933) ========= 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.oZSk6bntaS/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.1mO0iadicZ/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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-23 19:21:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.68M=0.002s 2016-05-23 19:21:21 (7.68 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090482 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:21.300654) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:21.301117) ========= 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.oZSk6bntaS/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.1mO0iadicZ/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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.020491 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:21.350506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 19:21:21.350859) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --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 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oZSk6bntaS/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.1mO0iadicZ/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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-23 19:21:21,396 truncating revision to first 12 chars 2016-05-23 19:21:21,396 Setting DEBUG logging. 2016-05-23 19:21:21,396 attempt 1/10 2016-05-23 19:21:21,396 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/46fe2115d46a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 19:21:22,902 unpacking tar archive at: mozilla-central-46fe2115d46a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.764877 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 19:21:23.133514) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:23.133984) ========= script_repo_revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:23.134597) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:23.135032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 19:21:23.148917) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-23 19:21:23.149557) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oZSk6bntaS/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.1mO0iadicZ/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:21:23 INFO - MultiFileLogger online at 20160523 19:21:23 in /builds/slave/test 19:21:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:21:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:21:23 INFO - {'append_to_log': False, 19:21:23 INFO - 'base_work_dir': '/builds/slave/test', 19:21:23 INFO - 'blob_upload_branch': 'Firefox', 19:21:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:21:23 INFO - 'branch': 'Firefox', 19:21:23 INFO - 'buildbot_json_path': 'buildprops.json', 19:21:23 INFO - 'config_files': ('talos/mac_config.py',), 19:21:23 INFO - 'default_actions': ('clobber', 19:21:23 INFO - 'read-buildbot-config', 19:21:23 INFO - 'download-and-extract', 19:21:23 INFO - 'populate-webroot', 19:21:23 INFO - 'create-virtualenv', 19:21:23 INFO - 'install', 19:21:23 INFO - 'run-tests'), 19:21:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:21:23 INFO - 'download_minidump_stackwalk': True, 19:21:23 INFO - 'download_symbols': 'ondemand', 19:21:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:21:23 INFO - 'tooltool.py': '/tools/tooltool.py', 19:21:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:21:23 INFO - '/tools/misc-python/virtualenv.py')}, 19:21:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:21:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:21:23 INFO - 'installer_path': 'installer.exe', 19:21:23 INFO - 'log_level': 'info', 19:21:23 INFO - 'log_name': 'talos', 19:21:23 INFO - 'log_to_console': True, 19:21:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:21:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:21:23 INFO - 'opt_config_files': (), 19:21:23 INFO - 'pip_index': False, 19:21:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:21:23 INFO - 'cmd': ('bash', 19:21:23 INFO - '-c', 19:21:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:21:23 INFO - 'enabled': True, 19:21:23 INFO - 'halt_on_failure': False, 19:21:23 INFO - 'name': 'check_screen_resolution'},), 19:21:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:21:23 INFO - 'cmd': ('bash', 19:21:23 INFO - '-c', 19:21:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:21:23 INFO - 'enabled': True, 19:21:23 INFO - 'halt_on_failure': False, 19:21:23 INFO - 'name': 'check_screen_resolution'},), 19:21:23 INFO - 'run_cmd_checks_enabled': True, 19:21:23 INFO - 'sps_profile': False, 19:21:23 INFO - 'sps_profile_interval': 0, 19:21:23 INFO - 'suite': 'dromaeojs-e10s', 19:21:23 INFO - 'system_bits': '32', 19:21:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:21:23 INFO - 'title': 't-yosemite-r7-0311', 19:21:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:21:23 INFO - 'use_talos_json': True, 19:21:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:21:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:21:23 INFO - 'work_dir': 'build'} 19:21:23 INFO - ##### 19:21:23 INFO - ##### Running clobber step. 19:21:23 INFO - ##### 19:21:23 INFO - Running pre-action listener: _resource_record_pre_action 19:21:23 INFO - Running main action method: clobber 19:21:23 INFO - rmtree: /builds/slave/test/build 19:21:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:21:24 INFO - Running post-action listener: _resource_record_post_action 19:21:24 INFO - ##### 19:21:24 INFO - ##### Finished clobber step (success) 19:21:24 INFO - ##### 19:21:24 INFO - ##### 19:21:24 INFO - ##### Running read-buildbot-config step. 19:21:24 INFO - ##### 19:21:24 INFO - Running pre-action listener: _resource_record_pre_action 19:21:24 INFO - Running main action method: read_buildbot_config 19:21:24 INFO - Using buildbot properties: 19:21:24 INFO - { 19:21:24 INFO - "project": "", 19:21:24 INFO - "product": "firefox", 19:21:24 INFO - "script_repo_revision": "production", 19:21:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:21:24 INFO - "repository": "", 19:21:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:21:24 INFO - "buildid": "20160523171639", 19:21:24 INFO - "pgo_build": "False", 19:21:24 INFO - "basedir": "/builds/slave/test", 19:21:24 INFO - "buildnumber": 47, 19:21:24 INFO - "slavename": "t-yosemite-r7-0311", 19:21:24 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 19:21:24 INFO - "platform": "yosemite_r7", 19:21:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:21:24 INFO - "branch": "mozilla-central", 19:21:24 INFO - "repo_path": "mozilla-central", 19:21:24 INFO - "revision": "46fe2115d46a5bb40523b8466341d8f9a26e1bdf", 19:21:24 INFO - "stage_platform": "macosx64", 19:21:24 INFO - "builduid": "2df6f63a3d754fefa021eb2c65da7322", 19:21:24 INFO - "slavebuilddir": "test" 19:21:24 INFO - } 19:21:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:21:24 INFO - Running post-action listener: _resource_record_post_action 19:21:24 INFO - ##### 19:21:24 INFO - ##### Finished read-buildbot-config step (success) 19:21:24 INFO - ##### 19:21:24 INFO - ##### 19:21:24 INFO - ##### Running download-and-extract step. 19:21:24 INFO - ##### 19:21:24 INFO - Running pre-action listener: _resource_record_pre_action 19:21:24 INFO - Running main action method: download_and_extract 19:21:24 INFO - mkdir: /builds/slave/test/build/tests 19:21:24 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')]} 19:21:24 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:21:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:21:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:21:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:25 INFO - Downloaded 1199 bytes. 19:21:25 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:21:25 INFO - Using the following test package requirements: 19:21:25 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:21:25 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:25 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:21:25 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:25 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:21:25 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:25 INFO - u'jsshell-mac.zip'], 19:21:25 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:25 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:21:25 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:21:25 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:25 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:21:25 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:25 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:21:25 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:25 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:21:25 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:21:25 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:21:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:21:25 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')]} 19:21:25 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:21:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:21:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:21:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:27 INFO - Downloaded 18630520 bytes. 19:21: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'] 19:21: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 19:21:30 INFO - Return code: 0 19:21:30 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 19:21:30 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')]} 19:21:30 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:21:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:21:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:21:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:31 INFO - Downloaded 18630520 bytes. 19:21:31 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'] 19:21:31 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 19:21:35 INFO - Return code: 0 19:21:35 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')]} 19:21:35 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:21:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:21:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:21:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:36 INFO - Downloaded 10840359 bytes. 19:21:36 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'] 19:21:36 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 19:21:36 INFO - Return code: 0 19:21:36 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')]} 19:21:36 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:21:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:21:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:21:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:21:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 19:21:39 INFO - Downloaded 110231948 bytes. 19:21:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:21:39 INFO - mkdir: /builds/slave/test/properties 19:21:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:21:39 INFO - Writing to file /builds/slave/test/properties/build_url 19:21:39 INFO - Contents: 19:21:39 INFO - build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:21:40 INFO - Running post-action listener: _resource_record_post_action 19:21:40 INFO - Running post-action listener: set_extra_try_arguments 19:21:40 INFO - ##### 19:21:40 INFO - ##### Finished download-and-extract step (success) 19:21:40 INFO - ##### 19:21:40 INFO - ##### 19:21:40 INFO - ##### Running populate-webroot step. 19:21:40 INFO - ##### 19:21:40 INFO - Running pre-action listener: _resource_record_pre_action 19:21:40 INFO - Running main action method: populate_webroot 19:21:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:21:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:21:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:21:40 INFO - '--tppagecycles', 19:21:40 INFO - '7'], 19:21:40 INFO - 'tests': ['tsvgm']}}, 19:21:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:21:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:21:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:21:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:21:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:40 INFO - 'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:21:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:21:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:40 INFO - 'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['damp', 'tps']}, 19:21:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:40 INFO - 'tests': ['damp', 'tps']}, 19:21:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['dromaeo_dom']}, 19:21:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:21:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['a11yr', 19:21:40 INFO - 'ts_paint', 19:21:40 INFO - 'tpaint', 19:21:40 INFO - 'sessionrestore', 19:21:40 INFO - 'sessionrestore_no_auto_restore', 19:21:40 INFO - 'tabpaint']}, 19:21:40 INFO - 'other-e10s': {'tests': ['a11yr', 19:21:40 INFO - 'ts_paint', 19:21:40 INFO - 'tpaint', 19:21:40 INFO - 'sessionrestore', 19:21:40 INFO - 'sessionrestore_no_auto_restore', 19:21:40 INFO - 'tabpaint']}, 19:21:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:21:40 INFO - 'ts_paint', 19:21:40 INFO - 'tpaint', 19:21:40 INFO - 'sessionrestore', 19:21:40 INFO - 'sessionrestore_no_auto_restore', 19:21:40 INFO - 'tabpaint']}, 19:21:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:21:40 INFO - 'ts_paint', 19:21:40 INFO - 'tpaint', 19:21:40 INFO - 'sessionrestore', 19:21:40 INFO - 'sessionrestore_no_auto_restore', 19:21:40 INFO - 'tabpaint']}, 19:21:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['a11yr', 19:21:40 INFO - 'ts_paint', 19:21:40 INFO - 'tpaint', 19:21:40 INFO - 'sessionrestore', 19:21:40 INFO - 'sessionrestore_no_auto_restore', 19:21:40 INFO - 'tabpaint']}, 19:21:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['a11yr', 19:21:40 INFO - 'ts_paint', 19:21:40 INFO - 'tpaint', 19:21:40 INFO - 'sessionrestore', 19:21:40 INFO - 'sessionrestore_no_auto_restore', 19:21:40 INFO - 'tabpaint']}, 19:21:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['tsvgx', 19:21:40 INFO - 'tsvgr_opacity', 19:21:40 INFO - 'tart', 19:21:40 INFO - 'tscrollx', 19:21:40 INFO - 'cart']}, 19:21:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:21:40 INFO - 'tsvgr_opacity', 19:21:40 INFO - 'tart', 19:21:40 INFO - 'tscrollx', 19:21:40 INFO - 'cart']}, 19:21:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:40 INFO - 'talos_options': ['--disable-e10s'], 19:21:40 INFO - 'tests': ['tp5o']}, 19:21:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:40 INFO - 'tests': ['tp5o']}, 19:21:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:40 INFO - 'talos_options': ['--disable-e10s', 19:21:40 INFO - '--xperf_path', 19:21:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:21:40 INFO - 'tests': ['tp5n']}, 19:21:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:21:40 INFO - 'talos_options': ['--xperf_path', 19:21:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:21:40 INFO - 'tests': ['tp5n']}}, 19:21:40 INFO - 'talos.zip': {'path': '', 19:21:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:21:40 INFO - Running post-action listener: _resource_record_post_action 19:21:40 INFO - ##### 19:21:40 INFO - ##### Finished populate-webroot step (success) 19:21:40 INFO - ##### 19:21:40 INFO - ##### 19:21:40 INFO - ##### Running create-virtualenv step. 19:21:40 INFO - ##### 19:21:40 INFO - Running pre-action listener: _resource_record_pre_action 19:21:40 INFO - Running main action method: create_virtualenv 19:21:40 INFO - Creating virtualenv /builds/slave/test/build/venv 19:21:40 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 19:21:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:21:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:21:41 INFO - Using real prefix '/tools/python27' 19:21:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:21:41 INFO - Installing distribute.............................................................................................................................................................................................done. 19:21:44 INFO - Installing pip.................done. 19:21:44 INFO - Return code: 0 19:21:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:21:44 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')]} 19:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:44 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')]} 19:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:44 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 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:21:44 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 19:21:44 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 19:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:44 INFO - 'HOME': '/Users/cltbld', 19:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:44 INFO - 'LOGNAME': 'cltbld', 19:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:44 INFO - 'MOZ_NO_REMOTE': '1', 19:21:44 INFO - 'NO_EM_RESTART': '1', 19:21:44 INFO - 'PAGER': '/bin/cat', 19:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:44 INFO - 'PWD': '/builds/slave/test', 19:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:44 INFO - 'SHELL': '/bin/bash', 19:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:21:44 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:44 INFO - 'USER': 'cltbld', 19:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:44 INFO - 'XPC_FLAGS': '0x0', 19:21:44 INFO - 'XPC_SERVICE_NAME': '0', 19:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:44 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 19:21:44 INFO - Cleaning up... 19:21:44 INFO - Return code: 0 19:21:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:21:44 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')]} 19:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:44 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')]} 19:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:44 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 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:21:44 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 19:21:44 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 19:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:44 INFO - 'HOME': '/Users/cltbld', 19:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:44 INFO - 'LOGNAME': 'cltbld', 19:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:44 INFO - 'MOZ_NO_REMOTE': '1', 19:21:44 INFO - 'NO_EM_RESTART': '1', 19:21:44 INFO - 'PAGER': '/bin/cat', 19:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:44 INFO - 'PWD': '/builds/slave/test', 19:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:44 INFO - 'SHELL': '/bin/bash', 19:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:21:44 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:44 INFO - 'USER': 'cltbld', 19:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:44 INFO - 'XPC_FLAGS': '0x0', 19:21:44 INFO - 'XPC_SERVICE_NAME': '0', 19:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:45 INFO - Downloading/unpacking psutil>=3.1.1 19:21: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 19:21: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 19:21: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 19:21: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 19:21: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 19:21: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 19:21:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:21:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:21:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:21:48 INFO - Installing collected packages: psutil 19:21:48 INFO - Running setup.py install for psutil 19:21:48 INFO - building 'psutil._psutil_osx' extension 19:21:48 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 19:21:48 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 19:21:48 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 19:21:48 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 19:21:48 INFO - building 'psutil._psutil_posix' extension 19:21:48 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 19:21:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:21:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:21:48 INFO - ^ 19:21:48 INFO - 1 warning generated. 19:21:48 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 19:21:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:21:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:21:48 INFO - Successfully installed psutil 19:21:48 INFO - Cleaning up... 19:21:49 INFO - Return code: 0 19:21:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:21:49 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')]} 19:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:49 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')]} 19:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:49 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 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:21:49 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 19:21:49 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 19:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:49 INFO - 'HOME': '/Users/cltbld', 19:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:49 INFO - 'LOGNAME': 'cltbld', 19:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:49 INFO - 'MOZ_NO_REMOTE': '1', 19:21:49 INFO - 'NO_EM_RESTART': '1', 19:21:49 INFO - 'PAGER': '/bin/cat', 19:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:49 INFO - 'PWD': '/builds/slave/test', 19:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:49 INFO - 'SHELL': '/bin/bash', 19:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:21:49 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:49 INFO - 'USER': 'cltbld', 19:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:49 INFO - 'XPC_FLAGS': '0x0', 19:21:49 INFO - 'XPC_SERVICE_NAME': '0', 19:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:49 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 19:21:49 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 19:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:21:51 INFO - Installing collected packages: mozsystemmonitor 19:21:51 INFO - Running setup.py install for mozsystemmonitor 19:21:51 INFO - Successfully installed mozsystemmonitor 19:21:51 INFO - Cleaning up... 19:21:51 INFO - Return code: 0 19:21:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:51 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 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:21:51 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 19:21:51 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 19:21:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:21:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:51 INFO - 'HOME': '/Users/cltbld', 19:21:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:51 INFO - 'LOGNAME': 'cltbld', 19:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:51 INFO - 'MOZ_NO_REMOTE': '1', 19:21:51 INFO - 'NO_EM_RESTART': '1', 19:21:51 INFO - 'PAGER': '/bin/cat', 19:21:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:51 INFO - 'PWD': '/builds/slave/test', 19:21:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:51 INFO - 'SHELL': '/bin/bash', 19:21:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:21:51 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:21:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:51 INFO - 'USER': 'cltbld', 19:21:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:51 INFO - 'XPC_FLAGS': '0x0', 19:21:51 INFO - 'XPC_SERVICE_NAME': '0', 19:21:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:52 INFO - Downloading/unpacking blobuploader==1.2.4 19:21:52 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 19:21:52 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 19:21:52 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 19:21:52 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 19:21:52 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 19:21:52 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 19:21:54 INFO - Downloading blobuploader-1.2.4.tar.gz 19:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:21:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:21:54 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 19:21:54 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 19:21:54 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 19:21:54 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 19:21:54 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 19:21:54 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 19:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:21:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:55 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 19:21:55 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 19:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:21:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:21:55 INFO - Downloading docopt-0.6.1.tar.gz 19:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:21:55 INFO - Installing collected packages: blobuploader, requests, docopt 19:21:55 INFO - Running setup.py install for blobuploader 19:21:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:21:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:21:56 INFO - Running setup.py install for requests 19:21:56 INFO - Running setup.py install for docopt 19:21:56 INFO - Successfully installed blobuploader requests docopt 19:21:56 INFO - Cleaning up... 19:21:56 INFO - Return code: 0 19:21:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:21:56 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 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:21:56 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 19:21:56 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 19:21:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:21:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:21:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:21:56 INFO - 'HOME': '/Users/cltbld', 19:21:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:21:56 INFO - 'LOGNAME': 'cltbld', 19:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:21:56 INFO - 'MOZ_NO_REMOTE': '1', 19:21:56 INFO - 'NO_EM_RESTART': '1', 19:21:56 INFO - 'PAGER': '/bin/cat', 19:21:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:21:56 INFO - 'PWD': '/builds/slave/test', 19:21:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:21:56 INFO - 'SHELL': '/bin/bash', 19:21:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:21:56 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:21:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:21:56 INFO - 'USER': 'cltbld', 19:21:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:21:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:21:56 INFO - 'XPC_FLAGS': '0x0', 19:21:56 INFO - 'XPC_SERVICE_NAME': '0', 19:21:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:21:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-h8v7wH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-pb4BD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-0xL5u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-gH5RgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-BFtjWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-TUZXX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-DedH1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-qjVdlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-zdjmGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:21:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-mJV6xU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:21:58 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-kIf4t2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:21:58 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-pgcKr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:21:58 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-krz4yA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:21:58 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-oOiLre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:58 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-tDJeMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:21:58 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-ClTbrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:21:58 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-R66gkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:21:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:21:58 INFO - Running setup.py install for manifestparser 19:21:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:21:58 INFO - Running setup.py install for mozcrash 19:21:59 INFO - Running setup.py install for mozdebug 19:21:59 INFO - Running setup.py install for mozdevice 19:21:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:21:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:21:59 INFO - Running setup.py install for mozfile 19:21:59 INFO - Running setup.py install for mozhttpd 19:21:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:21:59 INFO - Running setup.py install for mozinfo 19:21:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:21:59 INFO - Running setup.py install for mozInstall 19:21:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:21:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:21:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:21:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:21:59 INFO - Running setup.py install for mozleak 19:22:00 INFO - Running setup.py install for mozlog 19:22:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:22:00 INFO - Running setup.py install for moznetwork 19:22:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:22:00 INFO - Running setup.py install for mozprocess 19:22:00 INFO - Running setup.py install for mozprofile 19:22:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:22:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:22:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:22:00 INFO - Running setup.py install for mozrunner 19:22:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:22:00 INFO - Running setup.py install for mozscreenshot 19:22:01 INFO - Running setup.py install for moztest 19:22:01 INFO - Running setup.py install for mozversion 19:22:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:22:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:22:01 INFO - Cleaning up... 19:22:01 INFO - Return code: 0 19:22:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:01 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 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:22:01 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 19:22:01 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 19:22:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:22:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:01 INFO - 'HOME': '/Users/cltbld', 19:22:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:01 INFO - 'LOGNAME': 'cltbld', 19:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:01 INFO - 'MOZ_NO_REMOTE': '1', 19:22:01 INFO - 'NO_EM_RESTART': '1', 19:22:01 INFO - 'PAGER': '/bin/cat', 19:22:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:01 INFO - 'PWD': '/builds/slave/test', 19:22:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:01 INFO - 'SHELL': '/bin/bash', 19:22:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:22:01 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:22:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:01 INFO - 'USER': 'cltbld', 19:22:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:01 INFO - 'XPC_FLAGS': '0x0', 19:22:01 INFO - 'XPC_SERVICE_NAME': '0', 19:22:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:22:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-P3LkoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:22:01 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)) 19:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:22:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-TyuXJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:22:01 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)) 19:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:22:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-_u4HkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:22:01 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)) 19:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:22:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-EUxwwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-Y5VG79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-pkeiLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-4OKUjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-e677uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-1rAvXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-J6ZWZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-Swx046-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-p2hWiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:22:02 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)) 19:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:22:02 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-igFNGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:22:03 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)) 19:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:22:03 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-TXh4ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:22:03 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)) 19:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:03 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-CqOGUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:03 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)) 19:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:22:03 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-MAFZmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:22:03 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)) 19:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:22:03 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-NZ7KuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:22:03 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)) 19:22:03 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)) 19:22:03 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)) 19:22:03 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)) 19:22:03 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)) 19:22:03 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)) 19:22:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:22: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 19:22: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 19:22: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 19:22: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 19:22: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 19:22: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 19:22:05 INFO - Downloading blessings-1.6.tar.gz 19:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:22:05 INFO - Installing collected packages: blessings 19:22:05 INFO - Running setup.py install for blessings 19:22:06 INFO - Successfully installed blessings 19:22:06 INFO - Cleaning up... 19:22:06 INFO - Return code: 0 19:22:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:22:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:22:06 INFO - Reading from file tmpfile_stdout 19:22:06 INFO - Current package versions: 19:22:06 INFO - blessings == 1.6 19:22:06 INFO - blobuploader == 1.2.4 19:22:06 INFO - docopt == 0.6.1 19:22:06 INFO - manifestparser == 1.1 19:22:06 INFO - mozInstall == 1.12 19:22:06 INFO - mozcrash == 0.17 19:22:06 INFO - mozdebug == 0.1 19:22:06 INFO - mozdevice == 0.48 19:22:06 INFO - mozfile == 1.2 19:22:06 INFO - mozhttpd == 0.7 19:22:06 INFO - mozinfo == 0.9 19:22:06 INFO - mozleak == 0.1 19:22:06 INFO - mozlog == 3.2 19:22:06 INFO - moznetwork == 0.27 19:22:06 INFO - mozprocess == 0.22 19:22:06 INFO - mozprofile == 0.28 19:22:06 INFO - mozrunner == 6.11 19:22:06 INFO - mozscreenshot == 0.1 19:22:06 INFO - mozsystemmonitor == 0.3 19:22:06 INFO - moztest == 0.7 19:22:06 INFO - mozversion == 1.4 19:22:06 INFO - psutil == 3.1.1 19:22:06 INFO - requests == 1.2.3 19:22:06 INFO - wsgiref == 0.1.2 19:22:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22: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')]} 19:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22: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')]} 19:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:06 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 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:22:06 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 19:22:06 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 19:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:06 INFO - 'HOME': '/Users/cltbld', 19:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:06 INFO - 'LOGNAME': 'cltbld', 19:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:06 INFO - 'MOZ_NO_REMOTE': '1', 19:22:06 INFO - 'NO_EM_RESTART': '1', 19:22:06 INFO - 'PAGER': '/bin/cat', 19:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:06 INFO - 'PWD': '/builds/slave/test', 19:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:06 INFO - 'SHELL': '/bin/bash', 19:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:22:06 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:06 INFO - 'USER': 'cltbld', 19:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:06 INFO - 'XPC_FLAGS': '0x0', 19:22:06 INFO - 'XPC_SERVICE_NAME': '0', 19:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 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)) 19:22:06 INFO - Cleaning up... 19:22:06 INFO - Return code: 0 19:22:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:22: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')]} 19:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22: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')]} 19:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:22: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', 'jsonschema'] in /builds/slave/test/build 19:22: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 jsonschema 19:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:06 INFO - 'HOME': '/Users/cltbld', 19:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:06 INFO - 'LOGNAME': 'cltbld', 19:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:06 INFO - 'MOZ_NO_REMOTE': '1', 19:22:06 INFO - 'NO_EM_RESTART': '1', 19:22:06 INFO - 'PAGER': '/bin/cat', 19:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:06 INFO - 'PWD': '/builds/slave/test', 19:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:06 INFO - 'SHELL': '/bin/bash', 19:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:22:06 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:06 INFO - 'USER': 'cltbld', 19:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:06 INFO - 'XPC_FLAGS': '0x0', 19:22:06 INFO - 'XPC_SERVICE_NAME': '0', 19:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:07 INFO - Downloading/unpacking jsonschema 19:22: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 19:22: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 19:22: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 19:22: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 19:22: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 19:22: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 19:22:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:22:09 INFO - Downloading/unpacking functools32 (from jsonschema) 19:22:09 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 19:22:09 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 19:22:09 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 19:22:09 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 19:22:09 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 19:22:09 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 19:22:09 INFO - Downloading functools32-3.2.3-2.tar.gz 19:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:22:09 INFO - warning: no files found matching '*.txt' 19:22:09 INFO - no previously-included directories found matching 'build' 19:22:09 INFO - no previously-included directories found matching 'dist' 19:22:09 INFO - no previously-included directories found matching '.git*' 19:22:09 INFO - Installing collected packages: jsonschema, functools32 19:22:09 INFO - Running setup.py install for functools32 19:22:10 INFO - warning: no files found matching '*.txt' 19:22:10 INFO - no previously-included directories found matching 'build' 19:22:10 INFO - no previously-included directories found matching 'dist' 19:22:10 INFO - no previously-included directories found matching '.git*' 19:22:10 INFO - Successfully installed jsonschema functools32 19:22:10 INFO - Cleaning up... 19:22:10 INFO - Return code: 0 19:22:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:22:10 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')]} 19:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:10 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')]} 19:22:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:10 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 0x104666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92c14cc1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, '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.oZSk6bntaS/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.1mO0iadicZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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 19:22:10 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 19:22:10 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 19:22:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:22:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:10 INFO - 'HOME': '/Users/cltbld', 19:22:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:10 INFO - 'LOGNAME': 'cltbld', 19:22:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:10 INFO - 'MOZ_NO_REMOTE': '1', 19:22:10 INFO - 'NO_EM_RESTART': '1', 19:22:10 INFO - 'PAGER': '/bin/cat', 19:22:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:10 INFO - 'PWD': '/builds/slave/test', 19:22:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:10 INFO - 'SHELL': '/bin/bash', 19:22:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:22:10 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:22:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:10 INFO - 'USER': 'cltbld', 19:22:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:10 INFO - 'XPC_FLAGS': '0x0', 19:22:10 INFO - 'XPC_SERVICE_NAME': '0', 19:22:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:10 INFO - Downloading/unpacking flake8 19:22: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 19:22: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 19:22: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 19:22: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 19:22: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 19:22: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 19:22:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:22:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:22:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:22:13 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 19:22:13 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 19:22:13 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 19:22:13 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 19:22:13 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 19:22:13 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 19:22:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:22:13 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 19:22:13 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 19:22:13 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 19:22:13 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 19:22:13 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 19:22:13 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 19:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:22:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:22:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:22:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:22:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:22:14 INFO - no previously-included directories found matching 'docs/_build' 19:22:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:22:14 INFO - Running setup.py install for pep8 19:22:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:22:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:22:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:22:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:22:14 INFO - no previously-included directories found matching 'docs/_build' 19:22:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:22:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:22:14 INFO - Cleaning up... 19:22:14 INFO - Return code: 0 19:22:14 INFO - Running post-action listener: _resource_record_post_action 19:22:14 INFO - Running post-action listener: _start_resource_monitoring 19:22:14 INFO - Starting resource monitoring. 19:22:14 INFO - ##### 19:22:14 INFO - ##### Finished create-virtualenv step (success) 19:22:14 INFO - ##### 19:22:14 INFO - ##### 19:22:14 INFO - ##### Running install step. 19:22:14 INFO - ##### 19:22:14 INFO - Running pre-action listener: _resource_record_pre_action 19:22:14 INFO - Running main action method: install 19:22:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:22:14 INFO - Reading from file tmpfile_stdout 19:22:14 INFO - Detecting whether we're running mozinstall >=1.0... 19:22:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:22:14 INFO - Reading from file tmpfile_stdout 19:22:14 INFO - Output received: 19:22:14 INFO - Usage: mozinstall [options] installer 19:22:14 INFO - Options: 19:22:14 INFO - -h, --help show this help message and exit 19:22:14 INFO - -d DEST, --destination=DEST 19:22:14 INFO - Directory to install application into. [default: 19:22:14 INFO - "/builds/slave/test"] 19:22:14 INFO - --app=APP Application being installed. [default: firefox] 19:22:14 INFO - mkdir: /builds/slave/test/build/application 19:22:14 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'] 19:22:14 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 19:22:48 INFO - Reading from file tmpfile_stdout 19:22:48 INFO - Output received: 19:22:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:22:48 INFO - Running post-action listener: _resource_record_post_action 19:22:48 INFO - ##### 19:22:48 INFO - ##### Finished install step (success) 19:22:48 INFO - ##### 19:22:48 INFO - ##### 19:22:48 INFO - ##### Running run-tests step. 19:22:48 INFO - ##### 19:22:48 INFO - Running pre-action listener: _resource_record_pre_action 19:22:48 INFO - Running main action method: run_tests 19:22:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:22:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:22:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:22:48 INFO - 2016-05-23 19:22:48.500 screenresolution[2218:19703] starting screenresolution argv=screenresolution get 19:22:48 INFO - 2016-05-23 19:22:48.502 screenresolution[2218:19703] Display 0: 1600x1200x32@60 19:22:48 INFO - 2016-05-23 19:22:48.508 screenresolution[2219:19705] starting screenresolution argv=screenresolution list 19:22:48 INFO - Available Modes on Display 0 19:22:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:22:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:22:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:22:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:22:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:22:48 INFO - Intel Iris: 19:22:48 INFO - Chipset Model: Intel Iris 19:22:48 INFO - Type: GPU 19:22:48 INFO - Bus: Built-In 19:22:48 INFO - VRAM (Dynamic, Max): 1536 MB 19:22:48 INFO - Vendor: Intel (0x8086) 19:22:48 INFO - Device ID: 0x0a2e 19:22:48 INFO - Revision ID: 0x0009 19:22:48 INFO - Displays: 19:22:48 INFO - Display: 19:22:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:22:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:22:48 INFO - Display Serial Number: 1600x1200 60 19:22:48 INFO - Main Display: Yes 19:22:48 INFO - Mirror: Off 19:22:48 INFO - Online: Yes 19:22:48 INFO - Rotation: Supported 19:22:48 INFO - Return code: 0 19:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:22:48 INFO - Python 2.7.3 19:22:48 INFO - Return code: 0 19:22:48 INFO - grabbing minidump binary from tooltool 19:22: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')]} 19:22:48 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 0x10450ad30>, '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 0x10432edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104501b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:22:48 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 19:22:48 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 19:22:48 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 19:22:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:22:48 INFO - Return code: 0 19:22:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:22:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:22:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:22:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:22:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:22:49 INFO - Return code: 0 19:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0311', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0311 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZSk6bntaS/Render', 19:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:49 INFO - 'HOME': '/Users/cltbld', 19:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:49 INFO - 'LOGNAME': 'cltbld', 19:22:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:22:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:49 INFO - 'MOZ_NO_REMOTE': '1', 19:22:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:22:49 INFO - 'NO_EM_RESTART': '1', 19:22:49 INFO - 'PAGER': '/bin/cat', 19:22:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:49 INFO - 'PWD': '/builds/slave/test', 19:22:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:49 INFO - 'SHELL': '/bin/bash', 19:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1mO0iadicZ/Listeners', 19:22:49 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 19:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:49 INFO - 'USER': 'cltbld', 19:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:49 INFO - 'XPC_FLAGS': '0x0', 19:22:49 INFO - 'XPC_SERVICE_NAME': '0', 19:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0311', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:22:50 INFO - mozversion application_buildid: 20160523171639 19:22:50 INFO - mozversion application_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 19:22:50 INFO - mozversion application_display_name: Nightly 19:22:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:22:50 INFO - mozversion application_name: Firefox 19:22:50 INFO - mozversion application_remotingname: firefox 19:22:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:22:50 INFO - mozversion application_vendor: Mozilla 19:22:50 INFO - mozversion application_version: 49.0a1 19:22:50 INFO - mozversion platform_buildid: 20160523171639 19:22:50 INFO - mozversion platform_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 19:22:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:22:50 INFO - mozversion platform_version: 49.0a1 19:22:50 INFO - using testdate: 1464056570 19:22:50 INFO - actual date: 1464056570 19:22:50 INFO - starting webserver on 'localhost:56229' 19:22:50 INFO - SUITE-START | Running 2 tests 19:22:50 INFO - TEST-START | dromaeo_css 19:22:50 INFO - operating with platform_type : mac_ 19:22:50 INFO - Initialising browser for dromaeo_css test... 19:22:50 INFO - TEST-INFO | started process 2237 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/tmpbDHrxI/profile http://localhost:56229/getInfo.html) 19:22:53 INFO - PROCESS | 2237 | __metrics Screen width/height:1600/1200 19:22:53 INFO - PROCESS | 2237 | colorDepth:24 19:22:53 INFO - PROCESS | 2237 | Browser inner width/height: 1024/689 19:22:53 INFO - PROCESS | 2237 | __metrics 19:22:53 ERROR - PROCESS | 2237 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 19:22:54 INFO - TEST-INFO | 2237: exit 0 19:22:54 INFO - Browser initialized. 19:22:54 INFO - Running cycle 1/1 for dromaeo_css test... 19:22:54 INFO - TEST-INFO | started process 2240 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/tmpbDHrxI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:23:06 INFO - PROCESS | 2240 | RSS: Main: 250028032 19:23:06 INFO - PROCESS | 2240 | 19:23:55 INFO - PROCESS | 2240 | 1464056635922 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] 19:26:05 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56229/tests/dromaeo/cssquery-ext.html) 19:26:07 INFO - PROCESS | 2240 | RSS: Main: 261373952 19:26:07 INFO - PROCESS | 2240 | 19:29:10 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56229/tests/dromaeo/cssquery-ext.html (next: http://localhost:56229/tests/dromaeo/cssquery-jquery.html) 19:29:10 INFO - PROCESS | 2240 | RSS: Main: 273608704 19:29:10 INFO - PROCESS | 2240 | 19:32:12 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56229/tests/dromaeo/cssquery-mootools.html) 19:32:13 INFO - PROCESS | 2240 | RSS: Main: 276156416 19:32:13 INFO - PROCESS | 2240 | 19:35:15 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56229/tests/dromaeo/cssquery-prototype.html) 19:35:15 INFO - PROCESS | 2240 | RSS: Main: 277405696 19:35:15 INFO - PROCESS | 2240 | 19:38:18 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56229/tests/dromaeo/cssquery-yui.html) 19:38:19 INFO - PROCESS | 2240 | RSS: Main: 280264704 19:38:19 INFO - PROCESS | 2240 | 19:41:21 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56229/tests/dromaeo/cssquery-yui.html (next: http://localhost:56229/tests/dromaeo/cssquery-dojo.html) 19:41:22 INFO - PROCESS | 2240 | RSS: Main: 281661440 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - PROCESS | 2240 | __start_tp_report 19:41:22 INFO - PROCESS | 2240 | _x_x_mozilla_page_load 19:41:22 INFO - PROCESS | 2240 | _x_x_mozilla_page_load_details 19:41:22 INFO - PROCESS | 2240 | |i|pagename|runs| 19:41:22 INFO - PROCESS | 2240 | |0;dojo.html;3498.790072595644;3523.48909408136;3535.946960795587;3559.5728512578494;3563.910902227444;4812.831550895719;5686.805770788134;5783.826485205445;5865.266841644794;5883.264591926009;5704.942950570493;5706.629069110505;5730.226419433376;5845.561582881291;5937.643741375509;5189.558887494558;5230.398504172012;5321.680699158043;5344.412114667383;5373.623846330758;156.23816377547158;4089.5705950875176;5301.416844147141;5325.3875804282425;5358.65168764031;5330.333708286465;5335.7865685372535;5368.275282836806;5368.812091576776;5373.570114390839;4433.334999750031;5395.541378982791;5403.351597808249;5422.349318081816;5474.863128421821;5212.8957420851375;5214.348206474191;5253.50091741284;5284.603654725873;5328.227407025998;5644.238027866227;5727.169560413717;5754.338251101135;5755;5775.653460792366;6179.165812615284;6292.685365731695;6317.210348706411;6322.367763223687;6362.713677884506;5680.375158732536;6118.051701986199;6244.594101383441;6263.530235232331;6342.873142537123;5997.640141591519;6026.758929642809;6029.0052141397055;6041;6049.455549000611;6149.538784591174;6235.282942461675;6404.071409645575;6411.455026322721;6418.8716225675225;5646.265985421869;6106.491003209229;6236.75052997875;6251.156093927352;6255.874882502325;5840.2991641003355;6291.087792270096;6326.367363263636;6328.272248691367;6338.397852204125;5821.505172060422;5911.167867190794;6138.75626760427;6259.467945224706;6285.151504546919;6200.81397558074;6249.843753906189;6250.156228909039;6302.1177035215105;6309.779157729457;5081.085404627129;5091.465396133352;5094.821681241138;5098.260752190986;5115;4422.027154026174;4447.199504089295;4503.121891231244;4580.564846339659;4581.931271030937;5938.109283607493;5970.164177015221;6013.548983826143;6014.488768454643;6067.1202675611785;6305.14880491137;6332.398422149889;6344.365563443619;6347.39699728525;6355.682215889279;5799.884002320015;5901.203337549462;5951.285845698458;5972.193753843176;5984.072468767261;5540.972295138498;5542.944570554243;5543.7782488700805;5544.695041772741;5561.972190139023;6450.870982580416;6454.322296158835;6461.903071453932;6468.3855033771715;6476.870462590816;6144.907826382519;6249.0626406039455;6269.216347956505;6310.873782524415;6334.398232167937;6479.5788273762055;6496.285408605144;6501.577397469149;6520.250171230369;6522.869542609026;6146.6004709693725;6301.054841773771;6323.9683801580695;6334.9366506334345;6335.081413195153;6264.373562643699;6271.153394291712;6327.620342779448;6330.778422755181;6346.397092276226;6120.510359171345;6144.723487442986;6263.561550691408;6270.529710271839;6288.993760998218;5679.858003549944;6134.079888016833;6259.467945224525;6268.780592679234;6320.399562041599;5099.36257967754;5689.573282003784;5779.36426993022;5827.329857066492;6169.537284703575;5892.175095486549;5892.381299963442;5900.380460051632;5927.911081333869;5946.554008449297;5756.942430575808;5794.536437084938;5838.4453476919625;5839.8540036497725;5840.707964601838 19:41:22 INFO - PROCESS | 2240 | |1;ext.html;465.27417229122574;492.2272032908333;516.3726072821521;526.5287567626976;541.2206422751237;39043.0239244019;41994;42104.36843447351;42129.78935105328;42246.57753422465;42952.9261768456;43647.90880227988;43664;43755.812209389565;43798.562014379844;9583.952080239607;9761.902380976171;9811.067948544876;9823.410595364265;9843.409395436262;9652;9770.658026969057;9771.657991970282;9774.657886973957;9791.069848364394;9720.659776907774;9761.219102471785;9797.216222702167;9803.166730827888;9818.852717209213;9710.223182145412;9752.21982241419;9757.024297570186;9772.26707996896;9789.265805064648;7795.5712435816;8630.913690863073;9742.756431089208;9779.804403911881;9782.315237933348;40364.394534082014;40862;41084.38373424401;41513.1697366051;41517.169656607;2776.9444611107865;3056.801307914978;3121.3757248550382;3355.8154301513405;3375.476801095834;27235.86382068077;28303.433931321466;29017.56473652897;29789.851050744823;29854.40291194164;2645.35188878724;2683.275515610774;2717.2391730315544;2739.7808175345926;2757.8758955847034;459138;467573;469249.6537517325;469841;470537;32140.517892231634;33109.337813244085;34241.48637770436;35154.82422587871;35279.64720352815;35432.64567354345;36044.639553604655;36320;36471.90584282476;36605.81697091497;14507;14878.553643390716;14981.475648352256;15017.174055426838;15041.699166016619;12263.509459621693;12276;12279.38603069843;12577.371131443391;12772.936135319263;7689.3464055555905;7695.730649427355;7720.845583088307;7833.060032796101;7929.167437419103;3258.9348213035605;3281.9671803281663;3286.605607327136;3300.6235510432452;3315.4860996545576;967.9177269931994;981.4896253947907;1011.7217765114506;1070.2294348069377;1100.356291569425;26902.461950761266;29958.55062174069;30448.238794029883;30898.845505772326;30965.225869352995;1045.6549338718205;1050.3067975136373;1090.3131027452655;1110.1729211737306;1172.9061675065975;11613.88386116145;13364.665883352995;15249.771253431207;15459.61350966235;15534.611634708997;610212;893337;938531;981506;1006780;62835.42911427251;62943.74112517724;63505.68247158827;63703;64045.679771600844;1260.930648814324;1441.2577522575882;1466.200920498331;1477.9704405912037;1481.0817293278021;30384.240393990327;30530.694693053672;30686.693133069275;31082.067537973897;31308.843455782575;53828.19257711059;53929;53972.46027539674;53989.460105400016;54870.17694734662;640481;662315;663706;663944;664498;26754.732452676002;26780;26867;27307.72692273052;27677;45926.31110533278;46453.53546464492;46768;47163.292550611084;47227;35188.47217291794;38169;38607.03237115316;38798;39499.80250098846;35093.47359789554;37467;38015.61984380121;38043.80978095092;38683.61316386913;34839.825800870836;37799.81100094592;38901.805490972365;38920.61079389283;39058;30821.921232756747;33443.66556334405;33834.66165338315;33989;34143;24742.628860567467;24810;25224.621630675923;25336.733163342127;25623 19:41:22 INFO - PROCESS | 2240 | |2;jquery.html;387.50205985308884;392.77219212856545;393.4314914947901;393.6653844232402;410.5299432150188;20049.699254511175;20478.999999999964;21222.57554848902;21336.253231136914;21386.25148119816;20651.586968260624;20862.791372086274;21502.139914403404;21681.70537448629;21937.670934935948;5855.297364316288;6058;6162.938370616282;6171.567990240685;6184.443400093991;5766.625169363999;5990.700464976756;5991.951408503494;6020.5785595008365;6055;5863.706814659271;5884.176215329857;5980.312264089642;5988.3712210217955;6010.96994515029;5642.097264437712;5861.736221870026;5886.852828679274;5964.075568286922;5992.161097446361;5129.256257842592;5177.456367081478;5206.661566998133;5216.8695782605355;5227.60792940531;20852.37442876716;22287.888560557254;22905.969231384628;24564.385890352707;26317.473650527074;2085.0408811946522;2256.9322920312416;2276.5560715660454;2307.3539408965516;2459.766322199388;15391.846081539154;15750.976186547952;15820.050796952104;15935.601609959727;16030.679386412206;1731.3680506614994;1743.3898135652546;1761.788585369751;1811.194018661696;1821.5446138465384;122884;126110;127231.36384318111;127812.36093819658;128667.99999999907;11027.61403350879;11422;11432.256903301259;11442.885571144348;11564.653060408202;14016.85983140176;14059.71880562383;14064.789028164585;14066.507672231422;14103.78844317336;8787.428817126865;8835.955820220857;8838.911610883808;8857.468551886908;8871.157240062184;8811.691590794291;8899.955500222457;8923.910760892439;9042.954785226164;9085.13691199335;5797.391273916262;6900.516963812485;6934.895976560356;6989.89515157273;7123.572585644878;1882.153031135967;1888.1031510032624;1895.1661269041579;1902.0789003106686;1930.6331796958532;871.7925672942893;913.3423934766957;919.7332773495611;1065.8934106589434;1080.3409919948824;21028.532581528605;21613.027413766104;21893.67159492609;21906.45233869166;21976.560468790536;957.6169532187041;961.2598299309632;998.4608311511847;1032.1897310611134;1052.6684094510206;9499;10315.690529284133;11119.943605357477;11646.41767911601;11736.706582335339;485671;509966.4501677542;601334;619284;620051;108952;120011;125418;127591.36204318919;129429;991.8462638290932;1000.1298869983016;1027.6249169053242;1049.2235745548392;1077.881433042349;17637.64724705473;18760.249590016247;19067.61864762725;19229.134688938753;19525.414237572895;51578;52937.47062529325;53000.20499692585;53018.73490632522;53297.20054199268;74145.51708965683;74677.87983180366;74900;77110.2288977103;77729;5338.706371149624;5727.971360143172;7625.3137217648455;8167.224113709188;8584.914150858662;11537.907500037241;12727.87272127304;12894.226346419246;13085.934570327468;13231.40458679381;9648.421094734338;11059.004689577976;11135.944320278346;11389.487473063566;11441.942790285995;9312.44125352454;10867.13062954946;10967.780644387227;11136.220464567403;11539.307641541534;8795.956020220114;10597.89402105969;11353.432328383382;11484.827727583926;11667;7305.232950540117;8064.758057258292;8317.625706843086;8333.416660833926;8463.703770368007;9601.903980960102;9750.951245244012;10437.295990830367;11167.944160279147;11194 19:41:22 INFO - PROCESS | 2240 | |3;mootools.html;1473.6536913825248;1540.0990420603944;1765.34682167598;1893.3562588719833;1912.4031433752816;7872.803179920505;8520.74437766868;8594.183552562505;8923.241524470419;8925;8535.615897284622;8656.437331573443;8733.30133589313;8740;8759.43063700859;2343.6133038048742;2372.2290255666976;2376.3583832365252;2380.761923807614;2394.030417680842;2290.3815969688176;2303.6199027160496;2314.710661167354;2326.6626339180807;2329.965050524235;1950.6098780244008;2345.179187284445;2353.6704861319345;2373.371056669984;2378.3102900158933;2312.9421764456024;2316.6409206573007;2322.907083716658;2323.7095363079616;2340.0522788270778;1932.9130189141522;2320.756320586348;2325.918592849242;2329.149860300978;2331.883405829719;8629.352798540134;8681.522516261603;8700.434471759316;8717.997430295534;8729.127087291283;5080.822171223989;5302.655327403706;5317.494837990384;5345.919811202835;5381.48875856793;3363.0583436637535;3437.5015622734813;3541.0262177901027;3565.6790888820133;3567.554055743032;889.8264838356523;893.9317515568898;980.3382716666222;1012.4583347908907;1035.063267742694;11424.543018279259;11458.828117578243;11518.136139789465;11521.36632485213;11535.192536522447;4599.2871104978785;4700.647451441156;4738.976305118521;4792.2811578262845;4794.376731024944;9755.463449510346;9776.853347199798;9794.412335259907;9797.216222702167;9820.901790982141;4696.872750539914;5076.822311219164;5101.69389836611;5223.033738758342;5289.2859463972645;4336.566343365638;4495.100979804052;4642.837500687459;4711.081339138904;4742.5020372861045;3653.3606618841595;3720.3861362875364;3747.6439738224826;3748.194138260287;3773.2453509298252;1281.62192153315;1281.9807702884277;1282.0256604980095;1282.0769046286846;1286.5175559165314;894.5661354243186;902.0888902208816;923.3951761596031;925.1118925831278;934.2666007184328;5393.9212157568645;5867.853303667443;5876.1479585459865;5942.257217847769;6038.818835434944;918.807050519385;940.3229674634252;984.8178087053918;991.8115558043949;997.1723469520281;3394.4399174135992;3954.5649978502342;3971.185906889081;4020.115574573589;4051.8784436466954;10234.6417875374;10620.522076506575;10636.574537018432;10650.840237396445;10658.467076646137;10494.895051049392;10637.46812659364;10649.84025239622;10665.41340226295;10728;8124.268815806606;8210.630521626541;8224.794380140544;8280.378971577034;8291.212334828182;5620.072688006433;5771.1920331154515;5834.970825145847;5839.211706419579;5887.499562537232;9728.951355243178;9849.901500984899;9963.302568820112;9971.40171589707;9971.650992215238;9871.851922221029;9894.06006429388;10160.187185025032;10169.491525423848;10205.744856378353;6417.7753778617525;6703.463722902254;6831;6920.031195632515;6978;8257.133001035045;8260.132686067876;8344.749657510285;8345.582720863811;8351.081381048201;7236.638168091679;7364.66858991336;7376.926230737624;7471.40228781707;7490.70037198506;7187.422259813838;7238.819029524304;7256.7822965311125;7266.564006159648;7289.307515785992;6863.382295593221;6988.965055174692;6993.230744618189;7004.509684322048;7013.333733295329;4791.616670666408;4832.685875418087;4868.41579010521;4943.703377797344;5078.162103252922;4775.2359622460235;4886.438059623047;4918.92621610669;4965.950340496549;5127.9744051190655 19:41:22 INFO - PROCESS | 2240 | |4;prototype.html;283.57039085785027;301.29947871199454;314.35243398598885;320.97753157278987;322.88214801597394;4951.480094590071;5416.941562206366;5503.862403439916;5747.856303592413;5755.194272801811;5077.365329333833;5533.667979921208;5577.665340079588;5602.495775380215;5674.744636491358;3642.471841582969;4255.744555356177;4286.271333873249;4302.9776005277545;4310.374995625632;3643.198496330803;3832.1414114154754;4312.892177695551;4333.523312435629;4335.458067741532;3741.420079887622;3765.265773174232;3887.708421868357;4122.42286079949;4236.9364459533135;3845.38473844185;4311.741295522279;4327.112941846945;4332.501762297345;4349.3041113421705;3140.02659175654;3749;3760.078780698719;3767.3595488766973;3772.5850156482766;4090.7341022833716;4502.819887204508;5341.626086173969;5356;5359.5444387227135;4135.257591879354;4238.766867822269;4421;4610.561996610317;4928.556429921325;4861.807493763451;5168.22476628505;5978.641281523122;5985.910211346833;6024.759009639609;3474.4885974600875;3940.6650434713083;4071.167974411497;4075.3683179107284;4114.547399786021;5684.914726279109;5883.764649414018;6191.349908259658;6294.779682711083;6297.496200303919;3226;3542.6988705960284;4150.149219410014;4164.751034441429;4169.312063509547;3455.8963231103107;3950.9012274692786;4094.6724262058965;4098.426220329156;4257.914841703148;4534.319852022157;4804.903901921942;4821.807127714922;4821.807127714922;4823.324734537167;5229.921551176735;5729.742161602738;5980.461758441761;5992.880142397127;6032.306284777307;1180.651707746217;1386.7919812028074;1398.3497673581833;1402.2988505747126;1405.33246707815;826.2604968553111;827.9337652987747;833.5582141465034;853.2875049333752;862.8791969124328;4348.717333373321;4663.626909847204;4680.578747912704;4685.554872287127;4743.31221972812;4376.277914144131;4381.474223093249;4875.68308059975;5004.323551610179;5243.382066374266;4150.688698347636;4153.709240353146;4209.4948606166845;4723.716577005391;4745.6440766942615;2943.4701753684544;3290.6380298167182;3293.2425542125447;3304.6364899860996;3304.9504257436156;5879.764809407661;6184.845378865474;6413.230412350548;6464.321246269168;6517.478601711851;5857.736401861926;5979;6175.783847565313;6353.4724789433485;6354.586951848115;6265.216847894105;6440.194975628046;6505.869882602321;6512.5766825156215;6543.607383557001;5047.4352950585435;5092.8472145835685;5533.529649979636;6174.876502469836;6215.223097112861;6059.394060593905;6128.816135515942;6148.723307451265;6158.846028849314;6165.72254248551;5257.737113144404;5831.950248955229;6301.605389951789;6429.196350456193;6519.282878883414;3831.3869780834934;3915.393114067324;4038.8990275243355;4039.6970227232487;4042.636162745367;5839.299284085937;6049.78825741097;6120.908186377297;6124.112003759341;6126.846828829137;5275.657082289639;5282.603804714585;5492.450754924475;5705.857353566194;5929.258842644669;5315.4153043166;5347.251384806208;5761.049426844524;5910.822675319747;5932.7033648318275;4890.437599675941;4944.851654450372;5026.623003274696;5884.882302354014;5911.940880591139;4857.684250523705;5390.948764990789;5979.252593425822;6001.669908155093;6037.969810150921;5682.801101961577;5889.705514724161;5898.941010590011;5899.528037757055;5911.733971971357 19:41:22 INFO - PROCESS | 2240 | |5;yui.html;651.0787236060971;681.3935597318386;694.2294053600502;697.6546609428334;716.5199316457972;15068.698626027473;15137.772933406008;15151.696966060686;15501.302441390151;15651.217439128055;13932;13964.650883727887;14072.71854562908;14090.225037622926;14103.435862565511;722.0613202836319;800.6917336825335;919.0396036142247;925.7084018534174;926.5182105305237;923.5105394141116;927.2118699105773;931.8229536388064;936.194872409727;938.9108034736689;984.4487087231138;986.4426598971594;987.279286121132;987.9604815807359;989.4211886046671;928.8049509602992;936.765808547863;964.5514835601444;981.190517822799;985.6747273399761;688.5421194905382;688.6006116452446;688.8622275544911;689.2005273882272;708.8121647763347;14742.557723268319;14801.333939972727;14830.406783728638;14887.478938237218;14964.850351496454;12435.440405181698;12575;12591.307478088702;12601.684957876034;12742.872571274262;9926.255530835217;10014.048665376778;10017.749556261078;10228.488575571266;10413.323133996342;3357.529945807589;3386.9491957620658;3401.9829900850586;3421.3157368526145;3499.6500349965054;80446;80837.19162808356;81165;81729;81993;9427.161703467895;9443.527823608792;9478.620855165855;9547.283953703361;9696.854547181798;21841.798701071275;21933.341999740034;22150.22474213395;22187.445313867283;22247;6038.698065096728;7363.595002274819;7401.481896267317;7449.664765085584;7550.282723141293;3958.7030972677167;4040.0505881117915;4045.292073887058;4049.230646177217;4049.7165198436414;3381.4758712399616;3461.6538346165685;3471.89584312471;3488.093095795061;3494.405950988338;610.2829175718513;618.3661746709569;624.9031400132986;634.2420807135521;637;560.4899541417291;589.281077085963;601.0803470689851;609.2627920216588;624.2010226909563;7960.4825686330205;8121.593920303962;8216.014078310642;8349.33205343571;8366.916330836735;529.9284596579489;580.4572724502603;585.7598384662354;609.186735707835;615.6367743031633;3862.517185351831;3868.903277418031;3886.2810380079777;3907.882763517099;4018.533235369128;83845.16154838496;87334.56332718422;87638;90376;90577;55213.72393138089;56762;74779;75189.24810751823;78551.60724196456;70427.64786176036;70678.9398159028;70778.64610676914;70794;71098.2890171102;6465.709043093166;7663.846723065396;7720.498167619086;7725.806854828449;7805.843883122419;43110.56889431066;43136.568634313255;43762.56237437585;44029.55970440255;44685.776571116934;54927;56359.71820140873;56636.716816415654;57205.141922872026;59141.704291478265;10216.642417515352;10233.539490723084;10292.176625870063;10325.070743633109;10686.679399618024;10705.357678539312;10870.728231794268;10948.124150067817;11072.778544429228;11163.665090047312;10087.092161705481;10239.334443261165;10317.587296508056;10682;10683.305585137252;10240.795184096425;10274.794504109726;10302.536385862804;10611.151107911503;10641.680749577525;9275.794146760878;9992.900070999198;10093.293469457067;10107.545160467944;10420.010099040579;7979.241972012649;8269.83460330778;8918.286537077149;9057.18485336323;9286.860697089414;8737.344699147463;8925.509096999727;9028.729138125867;9254.4910029949;9354.158125768714 19:41:22 INFO - PROCESS | 2240 | __end_tp_report 19:41:22 INFO - PROCESS | 2240 | __start_cc_report 19:41:22 INFO - PROCESS | 2240 | _x_x_mozilla_cycle_collect,419 19:41:22 INFO - PROCESS | 2240 | __end_cc_report 19:41:22 INFO - PROCESS | 2240 | __startTimestamp1464057682151__endTimestamp 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - PROCESS | 2240 | ------- Summary: start ------- 19:41:22 INFO - PROCESS | 2240 | Number of tests: 6 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - PROCESS | 2240 | [#0] dojo.html Cycles:165 Average:5766.90 Median:5946.55 stddev:777.15 (13.1%) stddev-sans-first:758.89 19:41:22 INFO - PROCESS | 2240 | Values: 3498.8 3523.5 3535.9 3559.6 3563.9 4812.8 5686.8 5783.8 5865.3 5883.3 5704.9 5706.6 5730.2 5845.6 5937.6 5189.6 5230.4 5321.7 5344.4 5373.6 156.2 4089.6 5301.4 5325.4 5358.7 5330.3 5335.8 5368.3 5368.8 5373.6 4433.3 5395.5 5403.4 5422.3 5474.9 5212.9 5214.3 5253.5 5284.6 5328.2 5644.2 5727.2 5754.3 5755.0 5775.7 6179.2 6292.7 6317.2 6322.4 6362.7 5680.4 6118.1 6244.6 6263.5 6342.9 5997.6 6026.8 6029.0 6041.0 6049.5 6149.5 6235.3 6404.1 6411.5 6418.9 5646.3 6106.5 6236.8 6251.2 6255.9 5840.3 6291.1 6326.4 6328.3 6338.4 5821.5 5911.2 6138.8 6259.5 6285.2 6200.8 6249.8 6250.2 6302.1 6309.8 5081.1 5091.5 5094.8 5098.3 5115.0 4422.0 4447.2 4503.1 4580.6 4581.9 5938.1 5970.2 6013.5 6014.5 6067.1 6305.1 6332.4 6344.4 6347.4 6355.7 5799.9 5901.2 5951.3 5972.2 5984.1 5541.0 5542.9 5543.8 5544.7 5562.0 6450.9 6454.3 6461.9 6468.4 6476.9 6144.9 6249.1 6269.2 6310.9 6334.4 6479.6 6496.3 6501.6 6520.3 6522.9 6146.6 6301.1 6324.0 6334.9 6335.1 6264.4 6271.2 6327.6 6330.8 6346.4 6120.5 6144.7 6263.6 6270.5 6289.0 5679.9 6134.1 6259.5 6268.8 6320.4 5099.4 5689.6 5779.4 5827.3 6169.5 5892.2 5892.4 5900.4 5927.9 5946.6 5756.9 5794.5 5838.4 5839.9 5840.7 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - PROCESS | 2240 | [#1] ext.html Cycles:180 Average:76998.47 Median:38106.40 stddev:188525.73 (494.7%) stddev-sans-first:188967.02 19:41:22 INFO - PROCESS | 2240 | Values: 465.3 492.2 516.4 526.5 541.2 39043.0 41994.0 42104.4 42129.8 42246.6 42952.9 43647.9 43664.0 43755.8 43798.6 9584.0 9761.9 9811.1 9823.4 9843.4 9652.0 9770.7 9771.7 9774.7 9791.1 9720.7 9761.2 9797.2 9803.2 9818.9 9710.2 9752.2 9757.0 9772.3 9789.3 7795.6 8630.9 9742.8 9779.8 9782.3 40364.4 40862.0 41084.4 41513.2 41517.2 2776.9 3056.8 3121.4 3355.8 3375.5 27235.9 28303.4 29017.6 29789.9 29854.4 2645.4 2683.3 2717.2 2739.8 2757.9 459138.0 467573.0 469249.7 469841.0 470537.0 32140.5 33109.3 34241.5 35154.8 35279.6 35432.6 36044.6 36320.0 36471.9 36605.8 14507.0 14878.6 14981.5 15017.2 15041.7 12263.5 12276.0 12279.4 12577.4 12772.9 7689.3 7695.7 7720.8 7833.1 7929.2 3258.9 3282.0 3286.6 3300.6 3315.5 967.9 981.5 1011.7 1070.2 1100.4 26902.5 29958.6 30448.2 30898.8 30965.2 1045.7 1050.3 1090.3 1110.2 1172.9 11613.9 13364.7 15249.8 15459.6 15534.6 610212.0 893337.0 938531.0 981506.0 1006780.0 62835.4 62943.7 63505.7 63703.0 64045.7 1260.9 1441.3 1466.2 1478.0 1481.1 30384.2 30530.7 30686.7 31082.1 31308.8 53828.2 53929.0 53972.5 53989.5 54870.2 640481.0 662315.0 663706.0 663944.0 664498.0 26754.7 26780.0 26867.0 27307.7 27677.0 45926.3 46453.5 46768.0 47163.3 47227.0 35188.5 38169.0 38607.0 38798.0 39499.8 35093.5 37467.0 38015.6 38043.8 38683.6 34839.8 37799.8 38901.8 38920.6 39058.0 30821.9 33443.7 33834.7 33989.0 34143.0 24742.6 24810.0 25224.6 25336.7 25623.0 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - PROCESS | 2240 | [#2] jquery.html Cycles:180 Average:34347.82 Median:2266.74 stddev:95465.53 (4211.6%) stddev-sans-first:95699.28 19:41:22 INFO - PROCESS | 2240 | Values: 387.5 392.8 393.4 393.7 410.5 20049.7 20479.0 21222.6 21336.3 21386.3 20651.6 20862.8 21502.1 21681.7 21937.7 5855.3 6058.0 6162.9 6171.6 6184.4 5766.6 5990.7 5992.0 6020.6 6055.0 5863.7 5884.2 5980.3 5988.4 6011.0 5642.1 5861.7 5886.9 5964.1 5992.2 5129.3 5177.5 5206.7 5216.9 5227.6 20852.4 22287.9 22906.0 24564.4 26317.5 2085.0 2256.9 2276.6 2307.4 2459.8 15391.8 15751.0 15820.1 15935.6 16030.7 1731.4 1743.4 1761.8 1811.2 1821.5 122884.0 126110.0 127231.4 127812.4 128668.0 11027.6 11422.0 11432.3 11442.9 11564.7 14016.9 14059.7 14064.8 14066.5 14103.8 8787.4 8836.0 8838.9 8857.5 8871.2 8811.7 8900.0 8923.9 9043.0 9085.1 5797.4 6900.5 6934.9 6989.9 7123.6 1882.2 1888.1 1895.2 1902.1 1930.6 871.8 913.3 919.7 1065.9 1080.3 21028.5 21613.0 21893.7 21906.5 21976.6 957.6 961.3 998.5 1032.2 1052.7 9499.0 10315.7 11119.9 11646.4 11736.7 485671.0 509966.5 601334.0 619284.0 620051.0 108952.0 120011.0 125418.0 127591.4 129429.0 991.8 1000.1 1027.6 1049.2 1077.9 17637.6 18760.2 19067.6 19229.1 19525.4 51578.0 52937.5 53000.2 53018.7 53297.2 74145.5 74677.9 74900.0 77110.2 77729.0 5338.7 5728.0 7625.3 8167.2 8584.9 11537.9 12727.9 12894.2 13085.9 13231.4 9648.4 11059.0 11135.9 11389.5 11441.9 9312.4 10867.1 10967.8 11136.2 11539.3 8796.0 10597.9 11353.4 11484.8 11667.0 7305.2 8064.8 8317.6 8333.4 8463.7 9601.9 9751.0 10437.3 11167.9 11194.0 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - PROCESS | 2240 | [#3] mootools.html Cycles:180 Average:5636.23 Median:5079.49 stddev:3168.20 (62.4%) stddev-sans-first:3161.64 19:41:22 INFO - PROCESS | 2240 | Values: 1473.7 1540.1 1765.3 1893.4 1912.4 7872.8 8520.7 8594.2 8923.2 8925.0 8535.6 8656.4 8733.3 8740.0 8759.4 2343.6 2372.2 2376.4 2380.8 2394.0 2290.4 2303.6 2314.7 2326.7 2330.0 1950.6 2345.2 2353.7 2373.4 2378.3 2312.9 2316.6 2322.9 2323.7 2340.1 1932.9 2320.8 2325.9 2329.1 2331.9 8629.4 8681.5 8700.4 8718.0 8729.1 5080.8 5302.7 5317.5 5345.9 5381.5 3363.1 3437.5 3541.0 3565.7 3567.6 889.8 893.9 980.3 1012.5 1035.1 11424.5 11458.8 11518.1 11521.4 11535.2 4599.3 4700.6 4739.0 4792.3 4794.4 9755.5 9776.9 9794.4 9797.2 9820.9 4696.9 5076.8 5101.7 5223.0 5289.3 4336.6 4495.1 4642.8 4711.1 4742.5 3653.4 3720.4 3747.6 3748.2 3773.2 1281.6 1282.0 1282.0 1282.1 1286.5 894.6 902.1 923.4 925.1 934.3 5393.9 5867.9 5876.1 5942.3 6038.8 918.8 940.3 984.8 991.8 997.2 3394.4 3954.6 3971.2 4020.1 4051.9 10234.6 10620.5 10636.6 10650.8 10658.5 10494.9 10637.5 10649.8 10665.4 10728.0 8124.3 8210.6 8224.8 8280.4 8291.2 5620.1 5771.2 5835.0 5839.2 5887.5 9729.0 9849.9 9963.3 9971.4 9971.7 9871.9 9894.1 10160.2 10169.5 10205.7 6417.8 6703.5 6831.0 6920.0 6978.0 8257.1 8260.1 8344.7 8345.6 8351.1 7236.6 7364.7 7376.9 7471.4 7490.7 7187.4 7238.8 7256.8 7266.6 7289.3 6863.4 6989.0 6993.2 7004.5 7013.3 4791.6 4832.7 4868.4 4943.7 5078.2 4775.2 4886.4 4918.9 4966.0 5128.0 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - PROCESS | 2240 | [#4] prototype.html Cycles:180 Average:4686.49 Median:5015.47 stddev:1487.71 (29.7%) stddev-sans-first:1454.71 19:41:22 INFO - PROCESS | 2240 | Values: 283.6 301.3 314.4 321.0 322.9 4951.5 5416.9 5503.9 5747.9 5755.2 5077.4 5533.7 5577.7 5602.5 5674.7 3642.5 4255.7 4286.3 4303.0 4310.4 3643.2 3832.1 4312.9 4333.5 4335.5 3741.4 3765.3 3887.7 4122.4 4236.9 3845.4 4311.7 4327.1 4332.5 4349.3 3140.0 3749.0 3760.1 3767.4 3772.6 4090.7 4502.8 5341.6 5356.0 5359.5 4135.3 4238.8 4421.0 4610.6 4928.6 4861.8 5168.2 5978.6 5985.9 6024.8 3474.5 3940.7 4071.2 4075.4 4114.5 5684.9 5883.8 6191.3 6294.8 6297.5 3226.0 3542.7 4150.1 4164.8 4169.3 3455.9 3950.9 4094.7 4098.4 4257.9 4534.3 4804.9 4821.8 4821.8 4823.3 5229.9 5729.7 5980.5 5992.9 6032.3 1180.7 1386.8 1398.3 1402.3 1405.3 826.3 827.9 833.6 853.3 862.9 4348.7 4663.6 4680.6 4685.6 4743.3 4376.3 4381.5 4875.7 5004.3 5243.4 4150.7 4153.7 4209.5 4723.7 4745.6 2943.5 3290.6 3293.2 3304.6 3305.0 5879.8 6184.8 6413.2 6464.3 6517.5 5857.7 5979.0 6175.8 6353.5 6354.6 6265.2 6440.2 6505.9 6512.6 6543.6 5047.4 5092.8 5533.5 6174.9 6215.2 6059.4 6128.8 6148.7 6158.8 6165.7 5257.7 5832.0 6301.6 6429.2 6519.3 3831.4 3915.4 4038.9 4039.7 4042.6 5839.3 6049.8 6120.9 6124.1 6126.8 5275.7 5282.6 5492.5 5705.9 5929.3 5315.4 5347.3 5761.0 5910.8 5932.7 4890.4 4944.9 5026.6 5884.9 5911.9 4857.7 5390.9 5979.3 6001.7 6038.0 5682.8 5889.7 5898.9 5899.5 5911.7 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - PROCESS | 2240 | [#5] yui.html Cycles:180 Average:17265.42 Median:3323.94 stddev:24178.91 (727.4%) stddev-sans-first:24214.56 19:41:22 INFO - PROCESS | 2240 | Values: 651.1 681.4 694.2 697.7 716.5 15068.7 15137.8 15151.7 15501.3 15651.2 13932.0 13964.7 14072.7 14090.2 14103.4 722.1 800.7 919.0 925.7 926.5 923.5 927.2 931.8 936.2 938.9 984.4 986.4 987.3 988.0 989.4 928.8 936.8 964.6 981.2 985.7 688.5 688.6 688.9 689.2 708.8 14742.6 14801.3 14830.4 14887.5 14964.9 12435.4 12575.0 12591.3 12601.7 12742.9 9926.3 10014.0 10017.7 10228.5 10413.3 3357.5 3386.9 3402.0 3421.3 3499.7 80446.0 80837.2 81165.0 81729.0 81993.0 9427.2 9443.5 9478.6 9547.3 9696.9 21841.8 21933.3 22150.2 22187.4 22247.0 6038.7 7363.6 7401.5 7449.7 7550.3 3958.7 4040.1 4045.3 4049.2 4049.7 3381.5 3461.7 3471.9 3488.1 3494.4 610.3 618.4 624.9 634.2 637.0 560.5 589.3 601.1 609.3 624.2 7960.5 8121.6 8216.0 8349.3 8366.9 529.9 580.5 585.8 609.2 615.6 3862.5 3868.9 3886.3 3907.9 4018.5 83845.2 87334.6 87638.0 90376.0 90577.0 55213.7 56762.0 74779.0 75189.2 78551.6 70427.6 70678.9 70778.6 70794.0 71098.3 6465.7 7663.8 7720.5 7725.8 7805.8 43110.6 43136.6 43762.6 44029.6 44685.8 54927.0 56359.7 56636.7 57205.1 59141.7 10216.6 10233.5 10292.2 10325.1 10686.7 10705.4 10870.7 10948.1 11072.8 11163.7 10087.1 10239.3 10317.6 10682.0 10683.3 10240.8 10274.8 10302.5 10611.2 10641.7 9275.8 9992.9 10093.3 10107.5 10420.0 7979.2 8269.8 8918.3 9057.2 9286.9 8737.3 8925.5 9028.7 9254.5 9354.2 19:41:22 INFO - PROCESS | 2240 | -------- Summary: end -------- 19:41:22 INFO - PROCESS | 2240 | 19:41:22 INFO - TEST-INFO | 2240: exit 0 19:41:22 INFO - TEST-OK | dromaeo_css | took 1112514ms 19:41:22 INFO - TEST-START | kraken 19:41:22 INFO - operating with platform_type : mac_ 19:41:22 INFO - Initialising browser for kraken test... 19:41:22 INFO - TEST-INFO | started process 2245 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/tmpEPX7Nt/profile http://localhost:56229/getInfo.html) 19:41:25 INFO - PROCESS | 2245 | __metrics Screen width/height:1600/1200 19:41:25 INFO - PROCESS | 2245 | colorDepth:24 19:41:25 INFO - PROCESS | 2245 | Browser inner width/height: 1024/689 19:41:25 INFO - PROCESS | 2245 | __metrics 19:41:25 ERROR - PROCESS | 2245 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 19:41:26 INFO - TEST-INFO | 2245: exit 0 19:41:26 INFO - Browser initialized. 19:41:26 INFO - Running cycle 1/1 for kraken test... 19:41:26 INFO - TEST-INFO | started process 2248 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/tmpEPX7Nt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:41:38 INFO - PROCESS | 2248 | RSS: Main: 249954304 19:41:38 INFO - PROCESS | 2248 | 19:41:40 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56229/tests/kraken/driver.html?testName=audio-beat-detection) 19:41:41 INFO - PROCESS | 2248 | RSS: Main: 261779456 19:41:41 INFO - PROCESS | 2248 | 19:41:44 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56229/tests/kraken/driver.html?testName=audio-dft) 19:41:45 INFO - PROCESS | 2248 | RSS: Main: 267034624 19:41:45 INFO - PROCESS | 2248 | 19:41:48 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56229/tests/kraken/driver.html?testName=audio-fft) 19:41:49 INFO - PROCESS | 2248 | RSS: Main: 266764288 19:41:49 INFO - PROCESS | 2248 | 19:41:51 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56229/tests/kraken/driver.html?testName=audio-oscillator) 19:41:52 INFO - PROCESS | 2248 | RSS: Main: 267411456 19:41:52 INFO - PROCESS | 2248 | 19:41:54 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:41:54 INFO - PROCESS | 2248 | RSS: Main: 268451840 19:41:54 INFO - PROCESS | 2248 | 19:41:57 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56229/tests/kraken/driver.html?testName=imaging-darkroom) 19:41:58 INFO - PROCESS | 2248 | RSS: Main: 269787136 19:41:58 INFO - PROCESS | 2248 | 19:42:01 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56229/tests/kraken/driver.html?testName=imaging-desaturate) 19:42:02 INFO - PROCESS | 2248 | RSS: Main: 270974976 19:42:02 INFO - PROCESS | 2248 | 19:42:05 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56229/tests/kraken/driver.html?testName=json-parse-financial) 19:42:06 INFO - PROCESS | 2248 | RSS: Main: 270794752 19:42:06 INFO - PROCESS | 2248 | 19:42:07 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:42:08 INFO - PROCESS | 2248 | RSS: Main: 269963264 19:42:08 INFO - PROCESS | 2248 | 19:42:10 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56229/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:42:11 INFO - PROCESS | 2248 | RSS: Main: 270172160 19:42:11 INFO - PROCESS | 2248 | 19:42:13 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:42:14 INFO - PROCESS | 2248 | RSS: Main: 270884864 19:42:14 INFO - PROCESS | 2248 | 19:42:17 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:42:17 INFO - PROCESS | 2248 | RSS: Main: 270454784 19:42:17 INFO - PROCESS | 2248 | 19:42:20 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:42:21 INFO - PROCESS | 2248 | RSS: Main: 270438400 19:42:21 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:56229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56229/tests/kraken/driver.html?testName=ai-astar) 19:42:23 INFO - PROCESS | 2248 | RSS: Main: 273317888 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | __start_tp_report 19:42:23 INFO - PROCESS | 2248 | _x_x_mozilla_page_load 19:42:23 INFO - PROCESS | 2248 | _x_x_mozilla_page_load_details 19:42:23 INFO - PROCESS | 2248 | |i|pagename|runs| 19:42:23 INFO - PROCESS | 2248 | |0;ai-astar;116;112;117;114;117;119;117;115;116;115 19:42:23 INFO - PROCESS | 2248 | |1;audio-beat-detection;158;125;149;207;150;167;147;119;196;144 19:42:23 INFO - PROCESS | 2248 | |2;audio-dft;194;202;218;202;249;288;211;196;198;197 19:42:23 INFO - PROCESS | 2248 | |3;audio-fft;70;78;73;179;59;58;63;74;134;59 19:42:23 INFO - PROCESS | 2248 | |4;audio-oscillator;80;83;141;78;82;91;122;64;79;141 19:42:23 INFO - PROCESS | 2248 | |5;imaging-gaussian-blur;88;90;120;85;107;132;89;86;88;93 19:42:23 INFO - PROCESS | 2248 | |6;imaging-darkroom;94;95;95;129;152;95;96;95;94;96 19:42:23 INFO - PROCESS | 2248 | |7;imaging-desaturate;148;152;92;90;92;90;92;133;83;145 19:42:23 INFO - PROCESS | 2248 | |8;json-parse-financial;59;61;58;60;60;59;59;116;62;59 19:42:23 INFO - PROCESS | 2248 | |9;json-stringify-tinderbox;67;66;68;81;79;74;71;68;96;94 19:42:23 INFO - PROCESS | 2248 | |10;stanford-crypto-aes;87;86;86;88;89;89;87;85;82;86 19:42:23 INFO - PROCESS | 2248 | |11;stanford-crypto-ccm;143;149;149;139;135;137;164;141;254;160 19:42:23 INFO - PROCESS | 2248 | |12;stanford-crypto-pbkdf2;153;191;148;153;202;230;228;164;167;165 19:42:23 INFO - PROCESS | 2248 | |13;stanford-crypto-sha256-iterative;63;90;90;88;90;61;79;86;88;83 19:42:23 INFO - PROCESS | 2248 | __end_tp_report 19:42:23 INFO - PROCESS | 2248 | __start_cc_report 19:42:23 INFO - PROCESS | 2248 | _x_x_mozilla_cycle_collect,1080 19:42:23 INFO - PROCESS | 2248 | __end_cc_report 19:42:23 INFO - PROCESS | 2248 | __startTimestamp1464057743696__endTimestamp 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | ------- Summary: start ------- 19:42:23 INFO - PROCESS | 2248 | Number of tests: 14 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#0] ai-astar Cycles:10 Average:115.80 Median:116.50 stddev:1.93 (1.7%) stddev-sans-first:2.05 19:42:23 INFO - PROCESS | 2248 | Values: 116.0 112.0 117.0 114.0 117.0 119.0 117.0 115.0 116.0 115.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#1] audio-beat-detection Cycles:10 Average:156.20 Median:154.00 stddev:27.82 (18.1%) stddev-sans-first:29.50 19:42:23 INFO - PROCESS | 2248 | Values: 158.0 125.0 149.0 207.0 150.0 167.0 147.0 119.0 196.0 144.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#2] audio-dft Cycles:10 Average:215.50 Median:206.50 stddev:30.30 (14.7%) stddev-sans-first:31.12 19:42:23 INFO - PROCESS | 2248 | Values: 194.0 202.0 218.0 202.0 249.0 288.0 211.0 196.0 198.0 197.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#3] audio-fft Cycles:10 Average:84.70 Median:66.50 stddev:39.92 (60.0%) stddev-sans-first:41.98 19:42:23 INFO - PROCESS | 2248 | Values: 70.0 78.0 73.0 179.0 59.0 58.0 63.0 74.0 134.0 59.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:79.50 stddev:27.91 (35.1%) stddev-sans-first:28.98 19:42:23 INFO - PROCESS | 2248 | Values: 80.0 83.0 141.0 78.0 82.0 91.0 122.0 64.0 79.0 141.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:88.00 stddev:16.34 (18.6%) stddev-sans-first:16.94 19:42:23 INFO - PROCESS | 2248 | Values: 88.0 90.0 120.0 85.0 107.0 132.0 89.0 86.0 88.0 93.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:95.00 stddev:19.95 (21.0%) stddev-sans-first:20.82 19:42:23 INFO - PROCESS | 2248 | Values: 94.0 95.0 95.0 129.0 152.0 95.0 96.0 95.0 94.0 96.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#7] imaging-desaturate Cycles:10 Average:111.70 Median:90.00 stddev:28.74 (31.9%) stddev-sans-first:27.32 19:42:23 INFO - PROCESS | 2248 | Values: 148.0 152.0 92.0 90.0 92.0 90.0 92.0 133.0 83.0 145.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:59.50 stddev:17.85 (30.0%) stddev-sans-first:18.79 19:42:23 INFO - PROCESS | 2248 | Values: 59.0 61.0 58.0 60.0 60.0 59.0 59.0 116.0 62.0 59.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#9] json-stringify-tinderbox Cycles:10 Average:76.40 Median:76.50 stddev:11.03 (14.4%) stddev-sans-first:11.16 19:42:23 INFO - PROCESS | 2248 | Values: 67.0 66.0 68.0 81.0 79.0 74.0 71.0 68.0 96.0 94.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:87.00 stddev:2.07 (2.4%) stddev-sans-first:2.19 19:42:23 INFO - PROCESS | 2248 | Values: 87.0 86.0 86.0 88.0 89.0 89.0 87.0 85.0 82.0 86.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#11] stanford-crypto-ccm Cycles:10 Average:157.10 Median:149.00 stddev:35.36 (23.7%) stddev-sans-first:37.14 19:42:23 INFO - PROCESS | 2248 | Values: 143.0 149.0 149.0 139.0 135.0 137.0 164.0 141.0 254.0 160.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.10 Median:179.00 stddev:30.81 (17.2%) stddev-sans-first:31.07 19:42:23 INFO - PROCESS | 2248 | Values: 153.0 191.0 148.0 153.0 202.0 230.0 228.0 164.0 167.0 165.0 19:42:23 INFO - PROCESS | 2248 | 19:42:23 INFO - PROCESS | 2248 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.80 Median:88.00 stddev:11.01 (12.5%) stddev-sans-first:9.35 19:42:23 INFO - PROCESS | 2248 | Values: 63.0 90.0 90.0 88.0 90.0 61.0 79.0 86.0 88.0 83.0 19:42:23 INFO - PROCESS | 2248 | -------- Summary: end -------- 19:42:23 INFO - PROCESS | 2248 | 19:42:24 INFO - TEST-INFO | 2248: exit 0 19:42:24 INFO - TEST-OK | kraken | took 61758ms 19:42:24 INFO - SUITE-END | took 1174s 19:42:24 INFO - Completed test suite (00:19:34) 19:42:24 INFO - javascript benchmark 19:42:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7136.160934315466, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3498.790072595644, 3523.48909408136, 3535.946960795587, 3559.5728512578494, 3563.910902227444, 4812.831550895719, 5686.805770788134, 5783.826485205445, 5865.266841644794, 5883.264591926009, 5704.942950570493, 5706.629069110505, 5730.226419433376, 5845.561582881291, 5937.643741375509, 5189.558887494558, 5230.398504172012, 5321.680699158043, 5344.412114667383, 5373.623846330758, 156.23816377547158, 4089.5705950875176, 5301.416844147141, 5325.3875804282425, 5358.65168764031, 5330.333708286465, 5335.7865685372535, 5368.275282836806, 5368.812091576776, 5373.570114390839, 4433.334999750031, 5395.541378982791, 5403.351597808249, 5422.349318081816, 5474.863128421821, 5212.8957420851375, 5214.348206474191, 5253.50091741284, 5284.603654725873, 5328.227407025998, 5644.238027866227, 5727.169560413717, 5754.338251101135, 5755.0, 5775.653460792366, 6179.165812615284, 6292.685365731695, 6317.210348706411, 6322.367763223687, 6362.713677884506, 5680.375158732536, 6118.051701986199, 6244.594101383441, 6263.530235232331, 6342.873142537123, 5997.640141591519, 6026.758929642809, 6029.0052141397055, 6041.0, 6049.455549000611, 6149.538784591174, 6235.282942461675, 6404.071409645575, 6411.455026322721, 6418.8716225675225, 5646.265985421869, 6106.491003209229, 6236.75052997875, 6251.156093927352, 6255.874882502325, 5840.2991641003355, 6291.087792270096, 6326.367363263636, 6328.272248691367, 6338.397852204125, 5821.505172060422, 5911.167867190794, 6138.75626760427, 6259.467945224706, 6285.151504546919, 6200.81397558074, 6249.843753906189, 6250.156228909039, 6302.1177035215105, 6309.779157729457, 5081.085404627129, 5091.465396133352, 5094.821681241138, 5098.260752190986, 5115.0, 4422.027154026174, 4447.199504089295, 4503.121891231244, 4580.564846339659, 4581.931271030937, 5938.109283607493, 5970.164177015221, 6013.548983826143, 6014.488768454643, 6067.1202675611785, 6305.14880491137, 6332.398422149889, 6344.365563443619, 6347.39699728525, 6355.682215889279, 5799.884002320015, 5901.203337549462, 5951.285845698458, 5972.193753843176, 5984.072468767261, 5540.972295138498, 5542.944570554243, 5543.7782488700805, 5544.695041772741, 5561.972190139023, 6450.870982580416, 6454.322296158835, 6461.903071453932, 6468.3855033771715, 6476.870462590816, 6144.907826382519, 6249.0626406039455, 6269.216347956505, 6310.873782524415, 6334.398232167937, 6479.5788273762055, 6496.285408605144, 6501.577397469149, 6520.250171230369, 6522.869542609026, 6146.6004709693725, 6301.054841773771, 6323.9683801580695, 6334.9366506334345, 6335.081413195153, 6264.373562643699, 6271.153394291712, 6327.620342779448, 6330.778422755181, 6346.397092276226, 6120.510359171345, 6144.723487442986, 6263.561550691408, 6270.529710271839, 6288.993760998218, 5679.858003549944, 6134.079888016833, 6259.467945224525, 6268.780592679234, 6320.399562041599, 5099.36257967754, 5689.573282003784, 5779.36426993022, 5827.329857066492, 6169.537284703575, 5892.175095486549, 5892.381299963442, 5900.380460051632, 5927.911081333869, 5946.554008449297, 5756.942430575808, 5794.536437084938, 5838.4453476919625, 5839.8540036497725, 5840.707964601838], "value": 5720.376029796703, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.27417229122574, 492.2272032908333, 516.3726072821521, 526.5287567626976, 541.2206422751237, 39043.0239244019, 41994.0, 42104.36843447351, 42129.78935105328, 42246.57753422465, 42952.9261768456, 43647.90880227988, 43664.0, 43755.812209389565, 43798.562014379844, 9583.952080239607, 9761.902380976171, 9811.067948544876, 9823.410595364265, 9843.409395436262, 9652.0, 9770.658026969057, 9771.657991970282, 9774.657886973957, 9791.069848364394, 9720.659776907774, 9761.219102471785, 9797.216222702167, 9803.166730827888, 9818.852717209213, 9710.223182145412, 9752.21982241419, 9757.024297570186, 9772.26707996896, 9789.265805064648, 7795.5712435816, 8630.913690863073, 9742.756431089208, 9779.804403911881, 9782.315237933348, 40364.394534082014, 40862.0, 41084.38373424401, 41513.1697366051, 41517.169656607, 2776.9444611107865, 3056.801307914978, 3121.3757248550382, 3355.8154301513405, 3375.476801095834, 27235.86382068077, 28303.433931321466, 29017.56473652897, 29789.851050744823, 29854.40291194164, 2645.35188878724, 2683.275515610774, 2717.2391730315544, 2739.7808175345926, 2757.8758955847034, 459138.0, 467573.0, 469249.6537517325, 469841.0, 470537.0, 32140.517892231634, 33109.337813244085, 34241.48637770436, 35154.82422587871, 35279.64720352815, 35432.64567354345, 36044.639553604655, 36320.0, 36471.90584282476, 36605.81697091497, 14507.0, 14878.553643390716, 14981.475648352256, 15017.174055426838, 15041.699166016619, 12263.509459621693, 12276.0, 12279.38603069843, 12577.371131443391, 12772.936135319263, 7689.3464055555905, 7695.730649427355, 7720.845583088307, 7833.060032796101, 7929.167437419103, 3258.9348213035605, 3281.9671803281663, 3286.605607327136, 3300.6235510432452, 3315.4860996545576, 967.9177269931994, 981.4896253947907, 1011.7217765114506, 1070.2294348069377, 1100.356291569425, 26902.461950761266, 29958.55062174069, 30448.238794029883, 30898.845505772326, 30965.225869352995, 1045.6549338718205, 1050.3067975136373, 1090.3131027452655, 1110.1729211737306, 1172.9061675065975, 11613.88386116145, 13364.665883352995, 15249.771253431207, 15459.61350966235, 15534.611634708997, 610212.0, 893337.0, 938531.0, 981506.0, 1006780.0, 62835.42911427251, 62943.74112517724, 63505.68247158827, 63703.0, 64045.679771600844, 1260.930648814324, 1441.2577522575882, 1466.200920498331, 1477.9704405912037, 1481.0817293278021, 30384.240393990327, 30530.694693053672, 30686.693133069275, 31082.067537973897, 31308.843455782575, 53828.19257711059, 53929.0, 53972.46027539674, 53989.460105400016, 54870.17694734662, 640481.0, 662315.0, 663706.0, 663944.0, 664498.0, 26754.732452676002, 26780.0, 26867.0, 27307.72692273052, 27677.0, 45926.31110533278, 46453.53546464492, 46768.0, 47163.292550611084, 47227.0, 35188.47217291794, 38169.0, 38607.03237115316, 38798.0, 39499.80250098846, 35093.47359789554, 37467.0, 38015.61984380121, 38043.80978095092, 38683.61316386913, 34839.825800870836, 37799.81100094592, 38901.805490972365, 38920.61079389283, 39058.0, 30821.921232756747, 33443.66556334405, 33834.66165338315, 33989.0, 34143.0, 24742.628860567467, 24810.0, 25224.621630675923, 25336.733163342127, 25623.0], "value": 18599.49934622439, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.50205985308884, 392.77219212856545, 393.4314914947901, 393.6653844232402, 410.5299432150188, 20049.699254511175, 20478.999999999964, 21222.57554848902, 21336.253231136914, 21386.25148119816, 20651.586968260624, 20862.791372086274, 21502.139914403404, 21681.70537448629, 21937.670934935948, 5855.297364316288, 6058.0, 6162.938370616282, 6171.567990240685, 6184.443400093991, 5766.625169363999, 5990.700464976756, 5991.951408503494, 6020.5785595008365, 6055.0, 5863.706814659271, 5884.176215329857, 5980.312264089642, 5988.3712210217955, 6010.96994515029, 5642.097264437712, 5861.736221870026, 5886.852828679274, 5964.075568286922, 5992.161097446361, 5129.256257842592, 5177.456367081478, 5206.661566998133, 5216.8695782605355, 5227.60792940531, 20852.37442876716, 22287.888560557254, 22905.969231384628, 24564.385890352707, 26317.473650527074, 2085.0408811946522, 2256.9322920312416, 2276.5560715660454, 2307.3539408965516, 2459.766322199388, 15391.846081539154, 15750.976186547952, 15820.050796952104, 15935.601609959727, 16030.679386412206, 1731.3680506614994, 1743.3898135652546, 1761.788585369751, 1811.194018661696, 1821.5446138465384, 122884.0, 126110.0, 127231.36384318111, 127812.36093819658, 128667.99999999907, 11027.61403350879, 11422.0, 11432.256903301259, 11442.885571144348, 11564.653060408202, 14016.85983140176, 14059.71880562383, 14064.789028164585, 14066.507672231422, 14103.78844317336, 8787.428817126865, 8835.955820220857, 8838.911610883808, 8857.468551886908, 8871.157240062184, 8811.691590794291, 8899.955500222457, 8923.910760892439, 9042.954785226164, 9085.13691199335, 5797.391273916262, 6900.516963812485, 6934.895976560356, 6989.89515157273, 7123.572585644878, 1882.153031135967, 1888.1031510032624, 1895.1661269041579, 1902.0789003106686, 1930.6331796958532, 871.7925672942893, 913.3423934766957, 919.7332773495611, 1065.8934106589434, 1080.3409919948824, 21028.532581528605, 21613.027413766104, 21893.67159492609, 21906.45233869166, 21976.560468790536, 957.6169532187041, 961.2598299309632, 998.4608311511847, 1032.1897310611134, 1052.6684094510206, 9499.0, 10315.690529284133, 11119.943605357477, 11646.41767911601, 11736.706582335339, 485671.0, 509966.4501677542, 601334.0, 619284.0, 620051.0, 108952.0, 120011.0, 125418.0, 127591.36204318919, 129429.0, 991.8462638290932, 1000.1298869983016, 1027.6249169053242, 1049.2235745548392, 1077.881433042349, 17637.64724705473, 18760.249590016247, 19067.61864762725, 19229.134688938753, 19525.414237572895, 51578.0, 52937.47062529325, 53000.20499692585, 53018.73490632522, 53297.20054199268, 74145.51708965683, 74677.87983180366, 74900.0, 77110.2288977103, 77729.0, 5338.706371149624, 5727.971360143172, 7625.3137217648455, 8167.224113709188, 8584.914150858662, 11537.907500037241, 12727.87272127304, 12894.226346419246, 13085.934570327468, 13231.40458679381, 9648.421094734338, 11059.004689577976, 11135.944320278346, 11389.487473063566, 11441.942790285995, 9312.44125352454, 10867.13062954946, 10967.780644387227, 11136.220464567403, 11539.307641541534, 8795.956020220114, 10597.89402105969, 11353.432328383382, 11484.827727583926, 11667.0, 7305.232950540117, 8064.758057258292, 8317.625706843086, 8333.416660833926, 8463.703770368007, 9601.903980960102, 9750.951245244012, 10437.295990830367, 11167.944160279147, 11194.0], "value": 9764.112010503944, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.6536913825248, 1540.0990420603944, 1765.34682167598, 1893.3562588719833, 1912.4031433752816, 7872.803179920505, 8520.74437766868, 8594.183552562505, 8923.241524470419, 8925.0, 8535.615897284622, 8656.437331573443, 8733.30133589313, 8740.0, 8759.43063700859, 2343.6133038048742, 2372.2290255666976, 2376.3583832365252, 2380.761923807614, 2394.030417680842, 2290.3815969688176, 2303.6199027160496, 2314.710661167354, 2326.6626339180807, 2329.965050524235, 1950.6098780244008, 2345.179187284445, 2353.6704861319345, 2373.371056669984, 2378.3102900158933, 2312.9421764456024, 2316.6409206573007, 2322.907083716658, 2323.7095363079616, 2340.0522788270778, 1932.9130189141522, 2320.756320586348, 2325.918592849242, 2329.149860300978, 2331.883405829719, 8629.352798540134, 8681.522516261603, 8700.434471759316, 8717.997430295534, 8729.127087291283, 5080.822171223989, 5302.655327403706, 5317.494837990384, 5345.919811202835, 5381.48875856793, 3363.0583436637535, 3437.5015622734813, 3541.0262177901027, 3565.6790888820133, 3567.554055743032, 889.8264838356523, 893.9317515568898, 980.3382716666222, 1012.4583347908907, 1035.063267742694, 11424.543018279259, 11458.828117578243, 11518.136139789465, 11521.36632485213, 11535.192536522447, 4599.2871104978785, 4700.647451441156, 4738.976305118521, 4792.2811578262845, 4794.376731024944, 9755.463449510346, 9776.853347199798, 9794.412335259907, 9797.216222702167, 9820.901790982141, 4696.872750539914, 5076.822311219164, 5101.69389836611, 5223.033738758342, 5289.2859463972645, 4336.566343365638, 4495.100979804052, 4642.837500687459, 4711.081339138904, 4742.5020372861045, 3653.3606618841595, 3720.3861362875364, 3747.6439738224826, 3748.194138260287, 3773.2453509298252, 1281.62192153315, 1281.9807702884277, 1282.0256604980095, 1282.0769046286846, 1286.5175559165314, 894.5661354243186, 902.0888902208816, 923.3951761596031, 925.1118925831278, 934.2666007184328, 5393.9212157568645, 5867.853303667443, 5876.1479585459865, 5942.257217847769, 6038.818835434944, 918.807050519385, 940.3229674634252, 984.8178087053918, 991.8115558043949, 997.1723469520281, 3394.4399174135992, 3954.5649978502342, 3971.185906889081, 4020.115574573589, 4051.8784436466954, 10234.6417875374, 10620.522076506575, 10636.574537018432, 10650.840237396445, 10658.467076646137, 10494.895051049392, 10637.46812659364, 10649.84025239622, 10665.41340226295, 10728.0, 8124.268815806606, 8210.630521626541, 8224.794380140544, 8280.378971577034, 8291.212334828182, 5620.072688006433, 5771.1920331154515, 5834.970825145847, 5839.211706419579, 5887.499562537232, 9728.951355243178, 9849.901500984899, 9963.302568820112, 9971.40171589707, 9971.650992215238, 9871.851922221029, 9894.06006429388, 10160.187185025032, 10169.491525423848, 10205.744856378353, 6417.7753778617525, 6703.463722902254, 6831.0, 6920.031195632515, 6978.0, 8257.133001035045, 8260.132686067876, 8344.749657510285, 8345.582720863811, 8351.081381048201, 7236.638168091679, 7364.66858991336, 7376.926230737624, 7471.40228781707, 7490.70037198506, 7187.422259813838, 7238.819029524304, 7256.7822965311125, 7266.564006159648, 7289.307515785992, 6863.382295593221, 6988.965055174692, 6993.230744618189, 7004.509684322048, 7013.333733295329, 4791.616670666408, 4832.685875418087, 4868.41579010521, 4943.703377797344, 5078.162103252922, 4775.2359622460235, 4886.438059623047, 4918.92621610669, 4965.950340496549, 5127.9744051190655], "value": 4529.521105742848, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.57039085785027, 301.29947871199454, 314.35243398598885, 320.97753157278987, 322.88214801597394, 4951.480094590071, 5416.941562206366, 5503.862403439916, 5747.856303592413, 5755.194272801811, 5077.365329333833, 5533.667979921208, 5577.665340079588, 5602.495775380215, 5674.744636491358, 3642.471841582969, 4255.744555356177, 4286.271333873249, 4302.9776005277545, 4310.374995625632, 3643.198496330803, 3832.1414114154754, 4312.892177695551, 4333.523312435629, 4335.458067741532, 3741.420079887622, 3765.265773174232, 3887.708421868357, 4122.42286079949, 4236.9364459533135, 3845.38473844185, 4311.741295522279, 4327.112941846945, 4332.501762297345, 4349.3041113421705, 3140.02659175654, 3749.0, 3760.078780698719, 3767.3595488766973, 3772.5850156482766, 4090.7341022833716, 4502.819887204508, 5341.626086173969, 5356.0, 5359.5444387227135, 4135.257591879354, 4238.766867822269, 4421.0, 4610.561996610317, 4928.556429921325, 4861.807493763451, 5168.22476628505, 5978.641281523122, 5985.910211346833, 6024.759009639609, 3474.4885974600875, 3940.6650434713083, 4071.167974411497, 4075.3683179107284, 4114.547399786021, 5684.914726279109, 5883.764649414018, 6191.349908259658, 6294.779682711083, 6297.496200303919, 3226.0, 3542.6988705960284, 4150.149219410014, 4164.751034441429, 4169.312063509547, 3455.8963231103107, 3950.9012274692786, 4094.6724262058965, 4098.426220329156, 4257.914841703148, 4534.319852022157, 4804.903901921942, 4821.807127714922, 4821.807127714922, 4823.324734537167, 5229.921551176735, 5729.742161602738, 5980.461758441761, 5992.880142397127, 6032.306284777307, 1180.651707746217, 1386.7919812028074, 1398.3497673581833, 1402.2988505747126, 1405.33246707815, 826.2604968553111, 827.9337652987747, 833.5582141465034, 853.2875049333752, 862.8791969124328, 4348.717333373321, 4663.626909847204, 4680.578747912704, 4685.554872287127, 4743.31221972812, 4376.277914144131, 4381.474223093249, 4875.68308059975, 5004.323551610179, 5243.382066374266, 4150.688698347636, 4153.709240353146, 4209.4948606166845, 4723.716577005391, 4745.6440766942615, 2943.4701753684544, 3290.6380298167182, 3293.2425542125447, 3304.6364899860996, 3304.9504257436156, 5879.764809407661, 6184.845378865474, 6413.230412350548, 6464.321246269168, 6517.478601711851, 5857.736401861926, 5979.0, 6175.783847565313, 6353.4724789433485, 6354.586951848115, 6265.216847894105, 6440.194975628046, 6505.869882602321, 6512.5766825156215, 6543.607383557001, 5047.4352950585435, 5092.8472145835685, 5533.529649979636, 6174.876502469836, 6215.223097112861, 6059.394060593905, 6128.816135515942, 6148.723307451265, 6158.846028849314, 6165.72254248551, 5257.737113144404, 5831.950248955229, 6301.605389951789, 6429.196350456193, 6519.282878883414, 3831.3869780834934, 3915.393114067324, 4038.8990275243355, 4039.6970227232487, 4042.636162745367, 5839.299284085937, 6049.78825741097, 6120.908186377297, 6124.112003759341, 6126.846828829137, 5275.657082289639, 5282.603804714585, 5492.450754924475, 5705.857353566194, 5929.258842644669, 5315.4153043166, 5347.251384806208, 5761.049426844524, 5910.822675319747, 5932.7033648318275, 4890.437599675941, 4944.851654450372, 5026.623003274696, 5884.882302354014, 5911.940880591139, 4857.684250523705, 5390.948764990789, 5979.252593425822, 6001.669908155093, 6037.969810150921, 5682.801101961577, 5889.705514724161, 5898.941010590011, 5899.528037757055, 5911.733971971357], "value": 4209.39777866843, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.0787236060971, 681.3935597318386, 694.2294053600502, 697.6546609428334, 716.5199316457972, 15068.698626027473, 15137.772933406008, 15151.696966060686, 15501.302441390151, 15651.217439128055, 13932.0, 13964.650883727887, 14072.71854562908, 14090.225037622926, 14103.435862565511, 722.0613202836319, 800.6917336825335, 919.0396036142247, 925.7084018534174, 926.5182105305237, 923.5105394141116, 927.2118699105773, 931.8229536388064, 936.194872409727, 938.9108034736689, 984.4487087231138, 986.4426598971594, 987.279286121132, 987.9604815807359, 989.4211886046671, 928.8049509602992, 936.765808547863, 964.5514835601444, 981.190517822799, 985.6747273399761, 688.5421194905382, 688.6006116452446, 688.8622275544911, 689.2005273882272, 708.8121647763347, 14742.557723268319, 14801.333939972727, 14830.406783728638, 14887.478938237218, 14964.850351496454, 12435.440405181698, 12575.0, 12591.307478088702, 12601.684957876034, 12742.872571274262, 9926.255530835217, 10014.048665376778, 10017.749556261078, 10228.488575571266, 10413.323133996342, 3357.529945807589, 3386.9491957620658, 3401.9829900850586, 3421.3157368526145, 3499.6500349965054, 80446.0, 80837.19162808356, 81165.0, 81729.0, 81993.0, 9427.161703467895, 9443.527823608792, 9478.620855165855, 9547.283953703361, 9696.854547181798, 21841.798701071275, 21933.341999740034, 22150.22474213395, 22187.445313867283, 22247.0, 6038.698065096728, 7363.595002274819, 7401.481896267317, 7449.664765085584, 7550.282723141293, 3958.7030972677167, 4040.0505881117915, 4045.292073887058, 4049.230646177217, 4049.7165198436414, 3381.4758712399616, 3461.6538346165685, 3471.89584312471, 3488.093095795061, 3494.405950988338, 610.2829175718513, 618.3661746709569, 624.9031400132986, 634.2420807135521, 637.0, 560.4899541417291, 589.281077085963, 601.0803470689851, 609.2627920216588, 624.2010226909563, 7960.4825686330205, 8121.593920303962, 8216.014078310642, 8349.33205343571, 8366.916330836735, 529.9284596579489, 580.4572724502603, 585.7598384662354, 609.186735707835, 615.6367743031633, 3862.517185351831, 3868.903277418031, 3886.2810380079777, 3907.882763517099, 4018.533235369128, 83845.16154838496, 87334.56332718422, 87638.0, 90376.0, 90577.0, 55213.72393138089, 56762.0, 74779.0, 75189.24810751823, 78551.60724196456, 70427.64786176036, 70678.9398159028, 70778.64610676914, 70794.0, 71098.2890171102, 6465.709043093166, 7663.846723065396, 7720.498167619086, 7725.806854828449, 7805.843883122419, 43110.56889431066, 43136.568634313255, 43762.56237437585, 44029.55970440255, 44685.776571116934, 54927.0, 56359.71820140873, 56636.716816415654, 57205.141922872026, 59141.704291478265, 10216.642417515352, 10233.539490723084, 10292.176625870063, 10325.070743633109, 10686.679399618024, 10705.357678539312, 10870.728231794268, 10948.124150067817, 11072.778544429228, 11163.665090047312, 10087.092161705481, 10239.334443261165, 10317.587296508056, 10682.0, 10683.305585137252, 10240.795184096425, 10274.794504109726, 10302.536385862804, 10611.151107911503, 10641.680749577525, 9275.794146760878, 9992.900070999198, 10093.293469457067, 10107.545160467944, 10420.010099040579, 7979.241972012649, 8269.83460330778, 8918.286537077149, 9057.18485336323, 9286.860697089414, 8737.344699147463, 8925.509096999727, 9028.729138125867, 9254.4910029949, 9354.158125768714], "value": 6666.75029900843, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1629.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 117.0, 114.0, 117.0, 119.0, 117.0, 115.0, 116.0, 115.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 125.0, 149.0, 207.0, 150.0, 167.0, 147.0, 119.0, 196.0, 144.0], "value": 156.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 202.0, 218.0, 202.0, 249.0, 288.0, 211.0, 196.0, 198.0, 197.0], "value": 215.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 73.0, 179.0, 59.0, 58.0, 63.0, 74.0, 134.0, 59.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 141.0, 78.0, 82.0, 91.0, 122.0, 64.0, 79.0, 141.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 120.0, 85.0, 107.0, 132.0, 89.0, 86.0, 88.0, 93.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 129.0, 152.0, 95.0, 96.0, 95.0, 94.0, 96.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 92.0, 90.0, 92.0, 90.0, 92.0, 133.0, 83.0, 145.0], "value": 111.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 58.0, 60.0, 60.0, 59.0, 59.0, 116.0, 62.0, 59.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 81.0, 79.0, 74.0, 71.0, 68.0, 96.0, 94.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 88.0, 89.0, 89.0, 87.0, 85.0, 82.0, 86.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 149.0, 149.0, 139.0, 135.0, 137.0, 164.0, 141.0, 254.0, 160.0], "value": 157.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 191.0, 148.0, 153.0, 202.0, 230.0, 228.0, 164.0, 167.0, 165.0], "value": 180.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 90.0, 90.0, 88.0, 90.0, 61.0, 79.0, 86.0, 88.0, 83.0], "value": 81.8, "unit": "score"}]}]} 19:42:24 INFO - Return code: 0 19:42:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:42:25 ERROR - # TBPL SUCCESS # 19:42:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:42:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:42:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:42:25 INFO - 2016-05-23 19:42:25.018 screenresolution[2253:21959] starting screenresolution argv=screenresolution get 19:42:25 INFO - 2016-05-23 19:42:25.021 screenresolution[2253:21959] Display 0: 1600x1200x32@60 19:42:25 INFO - 2016-05-23 19:42:25.026 screenresolution[2254:21962] starting screenresolution argv=screenresolution list 19:42:25 INFO - Available Modes on Display 0 19:42:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:42:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:42:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:42:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:42:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:42:25 INFO - Intel Iris: 19:42:25 INFO - Chipset Model: Intel Iris 19:42:25 INFO - Type: GPU 19:42:25 INFO - Bus: Built-In 19:42:25 INFO - VRAM (Dynamic, Max): 1536 MB 19:42:25 INFO - Vendor: Intel (0x8086) 19:42:25 INFO - Device ID: 0x0a2e 19:42:25 INFO - Revision ID: 0x0009 19:42:25 INFO - Displays: 19:42:25 INFO - Display: 19:42:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:42:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:42:25 INFO - Display Serial Number: 1600x1200 60 19:42:25 INFO - Main Display: Yes 19:42:25 INFO - Mirror: Off 19:42:25 INFO - Online: Yes 19:42:25 INFO - Rotation: Supported 19:42:25 INFO - Return code: 0 19:42:25 INFO - Running post-action listener: _resource_record_post_action 19:42:25 INFO - ##### 19:42:25 INFO - ##### Finished run-tests step (success) 19:42:25 INFO - ##### 19:42:25 INFO - Running post-run listener: _resource_record_post_run 19:42:25 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 0; Write bytes: 695839744; Read time: 0; Write time: 1966 19:42:25 INFO - TinderboxPrint: CPU usage
54.3% 19:42:25 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:42:25 INFO - TinderboxPrint: I/O write bytes / time
695,839,744 / 1,966 19:42:25 INFO - TinderboxPrint: CPU idle
2,212.7 (45.7%) 19:42:25 INFO - TinderboxPrint: CPU system
976.3 (20.2%) 19:42:25 INFO - TinderboxPrint: CPU user
1,652.7 (34.1%) 19:42:25 INFO - TinderboxPrint: Swap in / out
395,792,384 / 0 19:42:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375360000; Write bytes: 368764928; Read time: 28372; Write time: 781 19:42:25 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 0; Write bytes: 296817664; Read time: 0; Write time: 1111 19:42:25 INFO - Running post-run listener: _upload_blobber_files 19:42:25 INFO - Blob upload gear active. 19:42:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:42: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 19:42: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'] 19:42: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 19:42:26 INFO - (blobuploader) - INFO - Open directory for files ... 19:42:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:42:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:42:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:26 INFO - (blobuploader) - INFO - Done attempting. 19:42:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:42:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:42:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:27 INFO - (blobuploader) - INFO - Done attempting. 19:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:42:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:30 INFO - (blobuploader) - INFO - Done attempting. 19:42:30 INFO - (blobuploader) - INFO - Iteration through files over. 19:42:30 INFO - Return code: 0 19:42:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:42:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:42:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/865a6931ed7cecc61195026d13a6de18aaf34c0ad7ddcb0c0ae980af13dbe9a7e8401bf43f277c1efe50d6f0cadd103ab2d4afb36b6a500003ed1fd527ca1d95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6962d288481bf92e81b5aa71e2e68fdec6d85d4f272ff54a0007d579b3365bfd0f692f63df8481ea5dcd9c7876b30a930110ac81008e1d18345f883c58e4cf13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d188ccb7573a5a50e72b66901162385fac27b41a85fd7a4c8267512e58a5851611c14d54a77a38c4f1a1248a3d70189c72511566722f48db664d17b124acad84"} 19:42:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:42:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:42:30 INFO - Contents: 19:42:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/865a6931ed7cecc61195026d13a6de18aaf34c0ad7ddcb0c0ae980af13dbe9a7e8401bf43f277c1efe50d6f0cadd103ab2d4afb36b6a500003ed1fd527ca1d95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6962d288481bf92e81b5aa71e2e68fdec6d85d4f272ff54a0007d579b3365bfd0f692f63df8481ea5dcd9c7876b30a930110ac81008e1d18345f883c58e4cf13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d188ccb7573a5a50e72b66901162385fac27b41a85fd7a4c8267512e58a5851611c14d54a77a38c4f1a1248a3d70189c72511566722f48db664d17b124acad84"} 19:42:30 INFO - Running post-run listener: copy_logs_to_upload_dir 19:42:30 INFO - Copying logs to upload dir... 19:42:30 INFO - mkdir: /builds/slave/test/build/upload/logs 19:42:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.118907 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-23 19:42:30.312583) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 19:42:30.315762) ========= 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.oZSk6bntaS/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.1mO0iadicZ/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/865a6931ed7cecc61195026d13a6de18aaf34c0ad7ddcb0c0ae980af13dbe9a7e8401bf43f277c1efe50d6f0cadd103ab2d4afb36b6a500003ed1fd527ca1d95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6962d288481bf92e81b5aa71e2e68fdec6d85d4f272ff54a0007d579b3365bfd0f692f63df8481ea5dcd9c7876b30a930110ac81008e1d18345f883c58e4cf13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d188ccb7573a5a50e72b66901162385fac27b41a85fd7a4c8267512e58a5851611c14d54a77a38c4f1a1248a3d70189c72511566722f48db664d17b124acad84"} build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010986 build_url: 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/865a6931ed7cecc61195026d13a6de18aaf34c0ad7ddcb0c0ae980af13dbe9a7e8401bf43f277c1efe50d6f0cadd103ab2d4afb36b6a500003ed1fd527ca1d95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6962d288481bf92e81b5aa71e2e68fdec6d85d4f272ff54a0007d579b3365bfd0f692f63df8481ea5dcd9c7876b30a930110ac81008e1d18345f883c58e4cf13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d188ccb7573a5a50e72b66901162385fac27b41a85fd7a4c8267512e58a5851611c14d54a77a38c4f1a1248a3d70189c72511566722f48db664d17b124acad84"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 19:42:30.385428) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 19:42:30.385760) ========= 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.oZSk6bntaS/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.1mO0iadicZ/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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.005497 ========= master_lag: 0.45 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 19:42:30.840959) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 19:42:30.841315) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 19:42:30.841650) ========= ========= Total master_lag: 0.62 =========