builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0254 starttime: 1463919433.42 results: success (0) buildid: 20160522030240 builduid: 8ff88d8cca2c41bb90111bfc67221d8e revision: 16663eb3dcfa759f25b5e27b101bc79270c156f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:13.421325) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:13.421802) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:13.422070) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:13.578358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:13.578605) ========= 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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-22 05:17:13-- 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% 38.2M=0s 2016-05-22 05:17:14 (38.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.675766 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:14.308836) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:14.309403) ========= 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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.020166 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:14.387710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-22 05:17:14.388070) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 16663eb3dcfa759f25b5e27b101bc79270c156f2 --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 16663eb3dcfa759f25b5e27b101bc79270c156f2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jU7aSAOmi9/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.8MZVw3TGXk/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-22 05:17:14,472 truncating revision to first 12 chars 2016-05-22 05:17:14,472 Setting DEBUG logging. 2016-05-22 05:17:14,472 attempt 1/10 2016-05-22 05:17:14,472 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/16663eb3dcfa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-22 05:17:16,042 unpacking tar archive at: mozilla-central-16663eb3dcfa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.827414 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-22 05:17:16.241194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:16.241531) ========= script_repo_revision: 16663eb3dcfa759f25b5e27b101bc79270c156f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:16.241895) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:16.242152) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 05:17:16.268742) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-22 05:17:16.268994) ========= /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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:17:16 INFO - MultiFileLogger online at 20160522 05:17:16 in /builds/slave/test 05:17:16 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 05:17:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:17:16 INFO - {'append_to_log': False, 05:17:16 INFO - 'base_work_dir': '/builds/slave/test', 05:17:16 INFO - 'blob_upload_branch': 'Firefox', 05:17:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:17:16 INFO - 'branch': 'Firefox', 05:17:16 INFO - 'buildbot_json_path': 'buildprops.json', 05:17:16 INFO - 'config_files': ('talos/mac_config.py',), 05:17:16 INFO - 'default_actions': ('clobber', 05:17:16 INFO - 'read-buildbot-config', 05:17:16 INFO - 'download-and-extract', 05:17:16 INFO - 'populate-webroot', 05:17:16 INFO - 'create-virtualenv', 05:17:16 INFO - 'install', 05:17:16 INFO - 'run-tests'), 05:17:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:17:16 INFO - 'download_minidump_stackwalk': True, 05:17:16 INFO - 'download_symbols': 'ondemand', 05:17:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:17:16 INFO - 'tooltool.py': '/tools/tooltool.py', 05:17:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:17:16 INFO - '/tools/misc-python/virtualenv.py')}, 05:17:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:17:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:17:16 INFO - 'installer_path': 'installer.exe', 05:17:16 INFO - 'log_level': 'info', 05:17:16 INFO - 'log_name': 'talos', 05:17:16 INFO - 'log_to_console': True, 05:17:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:17:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:17:16 INFO - 'opt_config_files': (), 05:17:16 INFO - 'pip_index': False, 05:17:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:16 INFO - 'cmd': ('bash', 05:17:16 INFO - '-c', 05:17:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:16 INFO - 'enabled': True, 05:17:16 INFO - 'halt_on_failure': False, 05:17:16 INFO - 'name': 'check_screen_resolution'},), 05:17:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:16 INFO - 'cmd': ('bash', 05:17:16 INFO - '-c', 05:17:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:16 INFO - 'enabled': True, 05:17:16 INFO - 'halt_on_failure': False, 05:17:16 INFO - 'name': 'check_screen_resolution'},), 05:17:16 INFO - 'run_cmd_checks_enabled': True, 05:17:16 INFO - 'sps_profile': False, 05:17:16 INFO - 'sps_profile_interval': 0, 05:17:16 INFO - 'suite': 'dromaeojs-e10s', 05:17:16 INFO - 'system_bits': '32', 05:17:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:17:16 INFO - 'title': 't-yosemite-r7-0254', 05:17:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:17:16 INFO - 'use_talos_json': True, 05:17:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:17:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:17:16 INFO - 'work_dir': 'build'} 05:17:16 INFO - ##### 05:17:16 INFO - ##### Running clobber step. 05:17:16 INFO - ##### 05:17:16 INFO - Running pre-action listener: _resource_record_pre_action 05:17:16 INFO - Running main action method: clobber 05:17:16 INFO - rmtree: /builds/slave/test/build 05:17:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:17:18 INFO - Running post-action listener: _resource_record_post_action 05:17:18 INFO - ##### 05:17:18 INFO - ##### Finished clobber step (success) 05:17:18 INFO - ##### 05:17:18 INFO - ##### 05:17:18 INFO - ##### Running read-buildbot-config step. 05:17:18 INFO - ##### 05:17:18 INFO - Running pre-action listener: _resource_record_pre_action 05:17:18 INFO - Running main action method: read_buildbot_config 05:17:18 INFO - Using buildbot properties: 05:17:18 INFO - { 05:17:18 INFO - "project": "", 05:17:18 INFO - "product": "firefox", 05:17:18 INFO - "script_repo_revision": "production", 05:17:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:17:18 INFO - "repository": "", 05:17:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:17:18 INFO - "buildid": "20160522030240", 05:17:18 INFO - "stage_platform": "macosx64", 05:17:18 INFO - "basedir": "/builds/slave/test", 05:17:18 INFO - "nightly_build": "True", 05:17:18 INFO - "buildnumber": 232, 05:17:18 INFO - "slavename": "t-yosemite-r7-0254", 05:17:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:17:18 INFO - "platform": "yosemite_r7", 05:17:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:17:18 INFO - "branch": "mozilla-central", 05:17:18 INFO - "repo_path": "mozilla-central", 05:17:18 INFO - "revision": "16663eb3dcfa759f25b5e27b101bc79270c156f2", 05:17:18 INFO - "pgo_build": "False", 05:17:18 INFO - "builduid": "8ff88d8cca2c41bb90111bfc67221d8e", 05:17:18 INFO - "slavebuilddir": "test" 05:17:18 INFO - } 05:17:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:17:18 INFO - Running post-action listener: _resource_record_post_action 05:17:18 INFO - ##### 05:17:18 INFO - ##### Finished read-buildbot-config step (success) 05:17:18 INFO - ##### 05:17:18 INFO - ##### 05:17:18 INFO - ##### Running download-and-extract step. 05:17:18 INFO - ##### 05:17:18 INFO - Running pre-action listener: _resource_record_pre_action 05:17:18 INFO - Running main action method: download_and_extract 05:17:18 INFO - mkdir: /builds/slave/test/build/tests 05:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:18 INFO - https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:17:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:17:26 INFO - Downloaded 1199 bytes. 05:17:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:26 INFO - Using the following test package requirements: 05:17:26 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:17:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:26 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:17:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:26 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:17:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:26 INFO - u'jsshell-mac.zip'], 05:17:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:26 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:17:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:17:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:26 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:17:26 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:26 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:17:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:26 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:17:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:26 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:17:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:26 INFO - https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:27 INFO - Downloaded 18630626 bytes. 05:17:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:17:30 INFO - Return code: 0 05:17: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 05:17: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')]} 05:17:30 INFO - https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:31 INFO - Downloaded 18630626 bytes. 05:17: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'] 05:17: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 05:17:35 INFO - Return code: 0 05:17: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')]} 05:17:35 INFO - https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:17:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:17:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:17:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:17:36 INFO - Downloaded 10840359 bytes. 05:17: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'] 05:17: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 05:17:36 INFO - Return code: 0 05:17: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')]} 05:17:36 INFO - https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:17:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:17:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:17:45 INFO - Downloaded 110341864 bytes. 05:17:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:45 INFO - mkdir: /builds/slave/test/properties 05:17:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:45 INFO - Contents: 05:17:45 INFO - build_url:https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:46 INFO - Running post-action listener: _resource_record_post_action 05:17:46 INFO - Running post-action listener: set_extra_try_arguments 05:17:46 INFO - ##### 05:17:46 INFO - ##### Finished download-and-extract step (success) 05:17:46 INFO - ##### 05:17:46 INFO - ##### 05:17:46 INFO - ##### Running populate-webroot step. 05:17:46 INFO - ##### 05:17:46 INFO - Running pre-action listener: _resource_record_pre_action 05:17:46 INFO - Running main action method: populate_webroot 05:17:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:46 INFO - '--tppagecycles', 05:17:46 INFO - '7'], 05:17:46 INFO - 'tests': ['tsvgm']}}, 05:17:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:17:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:17:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['damp', 'tps']}, 05:17:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'tests': ['damp', 'tps']}, 05:17:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['dromaeo_dom']}, 05:17:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other-e10s': {'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['tsvgx', 05:17:46 INFO - 'tsvgr_opacity', 05:17:46 INFO - 'tart', 05:17:46 INFO - 'tscrollx', 05:17:46 INFO - 'cart']}, 05:17:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:17:46 INFO - 'tsvgr_opacity', 05:17:46 INFO - 'tart', 05:17:46 INFO - 'tscrollx', 05:17:46 INFO - 'cart']}, 05:17:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['tp5o']}, 05:17:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'tests': ['tp5o']}, 05:17:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--disable-e10s', 05:17:46 INFO - '--xperf_path', 05:17:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:46 INFO - 'tests': ['tp5n']}, 05:17:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--xperf_path', 05:17:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:46 INFO - 'tests': ['tp5n']}}, 05:17:46 INFO - 'talos.zip': {'path': '', 05:17:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:46 INFO - Running post-action listener: _resource_record_post_action 05:17:46 INFO - ##### 05:17:46 INFO - ##### Finished populate-webroot step (success) 05:17:46 INFO - ##### 05:17:46 INFO - ##### 05:17:46 INFO - ##### Running create-virtualenv step. 05:17:46 INFO - ##### 05:17:46 INFO - Running pre-action listener: _resource_record_pre_action 05:17:46 INFO - Running main action method: create_virtualenv 05:17:46 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:17:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:46 INFO - Using real prefix '/tools/python27' 05:17:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:50 INFO - Installing pip.................done. 05:17:50 INFO - Return code: 0 05:17:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:50 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 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:50 INFO - 'HOME': '/Users/cltbld', 05:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:50 INFO - 'LOGNAME': 'cltbld', 05:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:50 INFO - 'MOZ_NO_REMOTE': '1', 05:17:50 INFO - 'NO_EM_RESTART': '1', 05:17:50 INFO - 'PAGER': '/bin/cat', 05:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:50 INFO - 'PWD': '/builds/slave/test', 05:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:50 INFO - 'SHELL': '/bin/bash', 05:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:17:50 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:50 INFO - 'USER': 'cltbld', 05:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:50 INFO - 'XPC_FLAGS': '0x0', 05:17:50 INFO - 'XPC_SERVICE_NAME': '0', 05:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:17:50 INFO - Cleaning up... 05:17:50 INFO - Return code: 0 05:17:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:50 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 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:50 INFO - 'HOME': '/Users/cltbld', 05:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:50 INFO - 'LOGNAME': 'cltbld', 05:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:50 INFO - 'MOZ_NO_REMOTE': '1', 05:17:50 INFO - 'NO_EM_RESTART': '1', 05:17:50 INFO - 'PAGER': '/bin/cat', 05:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:50 INFO - 'PWD': '/builds/slave/test', 05:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:50 INFO - 'SHELL': '/bin/bash', 05:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:17:50 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:50 INFO - 'USER': 'cltbld', 05:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:50 INFO - 'XPC_FLAGS': '0x0', 05:17:50 INFO - 'XPC_SERVICE_NAME': '0', 05:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:50 INFO - Downloading/unpacking psutil>=3.1.1 05:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:54 INFO - Installing collected packages: psutil 05:17:54 INFO - Running setup.py install for psutil 05:17:54 INFO - building 'psutil._psutil_osx' extension 05:17:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:17:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:17:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:17:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:17:54 INFO - building 'psutil._psutil_posix' extension 05:17:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:17:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:17:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:17:55 INFO - ^ 05:17:55 INFO - 1 warning generated. 05:17:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:17:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:55 INFO - Successfully installed psutil 05:17:55 INFO - Cleaning up... 05:17:55 INFO - Return code: 0 05:17:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:55 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 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:55 INFO - 'HOME': '/Users/cltbld', 05:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:55 INFO - 'LOGNAME': 'cltbld', 05:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:55 INFO - 'MOZ_NO_REMOTE': '1', 05:17:55 INFO - 'NO_EM_RESTART': '1', 05:17:55 INFO - 'PAGER': '/bin/cat', 05:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:55 INFO - 'PWD': '/builds/slave/test', 05:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:55 INFO - 'SHELL': '/bin/bash', 05:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:17:55 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:55 INFO - 'USER': 'cltbld', 05:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:55 INFO - 'XPC_FLAGS': '0x0', 05:17:55 INFO - 'XPC_SERVICE_NAME': '0', 05:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:17: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 05:17: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 05:17: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 05:17: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 05:17: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 05:17: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 05:17:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:17:58 INFO - Installing collected packages: mozsystemmonitor 05:17:58 INFO - Running setup.py install for mozsystemmonitor 05:17:58 INFO - Successfully installed mozsystemmonitor 05:17:58 INFO - Cleaning up... 05:17:58 INFO - Return code: 0 05:17:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:58 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 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:58 INFO - 'HOME': '/Users/cltbld', 05:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:58 INFO - 'LOGNAME': 'cltbld', 05:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:58 INFO - 'MOZ_NO_REMOTE': '1', 05:17:58 INFO - 'NO_EM_RESTART': '1', 05:17:58 INFO - 'PAGER': '/bin/cat', 05:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:58 INFO - 'PWD': '/builds/slave/test', 05:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:58 INFO - 'SHELL': '/bin/bash', 05:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:17:58 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:58 INFO - 'USER': 'cltbld', 05:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:58 INFO - 'XPC_FLAGS': '0x0', 05:17:58 INFO - 'XPC_SERVICE_NAME': '0', 05:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:58 INFO - Downloading/unpacking blobuploader==1.2.4 05:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:00 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:02 INFO - Downloading docopt-0.6.1.tar.gz 05:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:02 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:02 INFO - Running setup.py install for blobuploader 05:18:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:02 INFO - Running setup.py install for requests 05:18:02 INFO - Running setup.py install for docopt 05:18:03 INFO - Successfully installed blobuploader requests docopt 05:18:03 INFO - Cleaning up... 05:18:03 INFO - Return code: 0 05:18:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:03 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 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:03 INFO - 'HOME': '/Users/cltbld', 05:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:03 INFO - 'LOGNAME': 'cltbld', 05:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:03 INFO - 'MOZ_NO_REMOTE': '1', 05:18:03 INFO - 'NO_EM_RESTART': '1', 05:18:03 INFO - 'PAGER': '/bin/cat', 05:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:03 INFO - 'PWD': '/builds/slave/test', 05:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:03 INFO - 'SHELL': '/bin/bash', 05:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:18:03 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:03 INFO - 'USER': 'cltbld', 05:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:03 INFO - 'XPC_FLAGS': '0x0', 05:18:03 INFO - 'XPC_SERVICE_NAME': '0', 05:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-5wyNCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-K0Z0Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-3dIRWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-uA1Yxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-XDPudD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Z4MN6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-MWNNSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-RyPiqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Ck_2k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-W_gLka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-bejOGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-aJcK3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-m1QMBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-E_rJ8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-655QQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:05 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-STXXCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:05 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-ndI5jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:05 INFO - Running setup.py install for manifestparser 05:18:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:05 INFO - Running setup.py install for mozcrash 05:18:05 INFO - Running setup.py install for mozdebug 05:18:05 INFO - Running setup.py install for mozdevice 05:18:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:18:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:05 INFO - Running setup.py install for mozfile 05:18:05 INFO - Running setup.py install for mozhttpd 05:18:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for mozinfo 05:18:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for mozInstall 05:18:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for mozleak 05:18:06 INFO - Running setup.py install for mozlog 05:18:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for moznetwork 05:18:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for mozprocess 05:18:07 INFO - Running setup.py install for mozprofile 05:18:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:07 INFO - Running setup.py install for mozrunner 05:18:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:07 INFO - Running setup.py install for mozscreenshot 05:18:07 INFO - Running setup.py install for moztest 05:18:07 INFO - Running setup.py install for mozversion 05:18:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:07 INFO - Cleaning up... 05:18:07 INFO - Return code: 0 05:18:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:07 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 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:07 INFO - 'HOME': '/Users/cltbld', 05:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:07 INFO - 'LOGNAME': 'cltbld', 05:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:07 INFO - 'MOZ_NO_REMOTE': '1', 05:18:07 INFO - 'NO_EM_RESTART': '1', 05:18:07 INFO - 'PAGER': '/bin/cat', 05:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:07 INFO - 'PWD': '/builds/slave/test', 05:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:07 INFO - 'SHELL': '/bin/bash', 05:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:18:07 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:07 INFO - 'USER': 'cltbld', 05:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:07 INFO - 'XPC_FLAGS': '0x0', 05:18:07 INFO - 'XPC_SERVICE_NAME': '0', 05:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-5LKvtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-WSTNr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-4badZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-BKNtYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9Lbb4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-BRGPjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-vk7xwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-GLYn5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:08 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-jzPQ4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:09 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-bfJNBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:09 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-eiSZuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:09 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-tKhPOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:09 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-bP6vEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:09 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-hPsyN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:09 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-XVwIrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:09 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-M0HbGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:09 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-A_kpjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18:12 INFO - Downloading blessings-1.6.tar.gz 05:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:18:12 INFO - Installing collected packages: blessings 05:18:12 INFO - Running setup.py install for blessings 05:18:12 INFO - Successfully installed blessings 05:18:12 INFO - Cleaning up... 05:18:12 INFO - Return code: 0 05:18:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:18:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:12 INFO - Reading from file tmpfile_stdout 05:18:12 INFO - Current package versions: 05:18:12 INFO - blessings == 1.6 05:18:12 INFO - blobuploader == 1.2.4 05:18:12 INFO - docopt == 0.6.1 05:18:12 INFO - manifestparser == 1.1 05:18:12 INFO - mozInstall == 1.12 05:18:12 INFO - mozcrash == 0.17 05:18:12 INFO - mozdebug == 0.1 05:18:12 INFO - mozdevice == 0.48 05:18:12 INFO - mozfile == 1.2 05:18:12 INFO - mozhttpd == 0.7 05:18:12 INFO - mozinfo == 0.9 05:18:12 INFO - mozleak == 0.1 05:18:12 INFO - mozlog == 3.2 05:18:12 INFO - moznetwork == 0.27 05:18:12 INFO - mozprocess == 0.22 05:18:12 INFO - mozprofile == 0.28 05:18:12 INFO - mozrunner == 6.11 05:18:12 INFO - mozscreenshot == 0.1 05:18:12 INFO - mozsystemmonitor == 0.3 05:18:12 INFO - moztest == 0.7 05:18:12 INFO - mozversion == 1.4 05:18:12 INFO - psutil == 3.1.1 05:18:12 INFO - requests == 1.2.3 05:18:12 INFO - wsgiref == 0.1.2 05:18:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-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 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:18:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:12 INFO - 'HOME': '/Users/cltbld', 05:18:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:12 INFO - 'LOGNAME': 'cltbld', 05:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:12 INFO - 'MOZ_NO_REMOTE': '1', 05:18:12 INFO - 'NO_EM_RESTART': '1', 05:18:12 INFO - 'PAGER': '/bin/cat', 05:18:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:12 INFO - 'PWD': '/builds/slave/test', 05:18:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:12 INFO - 'SHELL': '/bin/bash', 05:18:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:18:12 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:18:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:12 INFO - 'USER': 'cltbld', 05:18:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:12 INFO - 'XPC_FLAGS': '0x0', 05:18:12 INFO - 'XPC_SERVICE_NAME': '0', 05:18:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:13 INFO - Cleaning up... 05:18:13 INFO - Return code: 0 05:18:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:13 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 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:13 INFO - 'HOME': '/Users/cltbld', 05:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:13 INFO - 'LOGNAME': 'cltbld', 05:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:13 INFO - 'MOZ_NO_REMOTE': '1', 05:18:13 INFO - 'NO_EM_RESTART': '1', 05:18:13 INFO - 'PAGER': '/bin/cat', 05:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:13 INFO - 'PWD': '/builds/slave/test', 05:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:13 INFO - 'SHELL': '/bin/bash', 05:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:18:13 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:13 INFO - 'USER': 'cltbld', 05:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:13 INFO - 'XPC_FLAGS': '0x0', 05:18:13 INFO - 'XPC_SERVICE_NAME': '0', 05:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:13 INFO - Downloading/unpacking jsonschema 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:15 INFO - Downloading/unpacking functools32 (from jsonschema) 05:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:16 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:16 INFO - warning: no files found matching '*.txt' 05:18:16 INFO - no previously-included directories found matching 'build' 05:18:16 INFO - no previously-included directories found matching 'dist' 05:18:16 INFO - no previously-included directories found matching '.git*' 05:18:16 INFO - Installing collected packages: jsonschema, functools32 05:18:16 INFO - Running setup.py install for functools32 05:18:16 INFO - warning: no files found matching '*.txt' 05:18:16 INFO - no previously-included directories found matching 'build' 05:18:16 INFO - no previously-included directories found matching 'dist' 05:18:16 INFO - no previously-included directories found matching '.git*' 05:18:16 INFO - Successfully installed jsonschema functools32 05:18:16 INFO - Cleaning up... 05:18:16 INFO - Return code: 0 05:18:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb161caead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, '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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:18:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:18:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:16 INFO - 'HOME': '/Users/cltbld', 05:18:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:16 INFO - 'LOGNAME': 'cltbld', 05:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:16 INFO - 'MOZ_NO_REMOTE': '1', 05:18:16 INFO - 'NO_EM_RESTART': '1', 05:18:16 INFO - 'PAGER': '/bin/cat', 05:18:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:16 INFO - 'PWD': '/builds/slave/test', 05:18:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:16 INFO - 'SHELL': '/bin/bash', 05:18:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:18:16 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:18:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:16 INFO - 'USER': 'cltbld', 05:18:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:16 INFO - 'XPC_FLAGS': '0x0', 05:18:16 INFO - 'XPC_SERVICE_NAME': '0', 05:18:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:17 INFO - Downloading/unpacking flake8 05:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:18:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:18:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:21 INFO - no previously-included directories found matching 'docs/_build' 05:18:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:18:21 INFO - Running setup.py install for pep8 05:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:21 INFO - no previously-included directories found matching 'docs/_build' 05:18:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:18:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:18:21 INFO - Cleaning up... 05:18:21 INFO - Return code: 0 05:18:21 INFO - Running post-action listener: _resource_record_post_action 05:18:21 INFO - Running post-action listener: _start_resource_monitoring 05:18:21 INFO - Starting resource monitoring. 05:18:21 INFO - ##### 05:18:21 INFO - ##### Finished create-virtualenv step (success) 05:18:21 INFO - ##### 05:18:21 INFO - ##### 05:18:21 INFO - ##### Running install step. 05:18:21 INFO - ##### 05:18:21 INFO - Running pre-action listener: _resource_record_pre_action 05:18:21 INFO - Running main action method: install 05:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:21 INFO - Reading from file tmpfile_stdout 05:18:21 INFO - Detecting whether we're running mozinstall >=1.0... 05:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:18:21 INFO - Reading from file tmpfile_stdout 05:18:21 INFO - Output received: 05:18:21 INFO - Usage: mozinstall [options] installer 05:18:21 INFO - Options: 05:18:21 INFO - -h, --help show this help message and exit 05:18:21 INFO - -d DEST, --destination=DEST 05:18:21 INFO - Directory to install application into. [default: 05:18:21 INFO - "/builds/slave/test"] 05:18:21 INFO - --app=APP Application being installed. [default: firefox] 05:18:21 INFO - mkdir: /builds/slave/test/build/application 05:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:18:55 INFO - Reading from file tmpfile_stdout 05:18:55 INFO - Output received: 05:18:55 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:18:55 INFO - Running post-action listener: _resource_record_post_action 05:18:55 INFO - ##### 05:18:55 INFO - ##### Finished install step (success) 05:18:55 INFO - ##### 05:18:55 INFO - ##### 05:18:55 INFO - ##### Running run-tests step. 05:18:55 INFO - ##### 05:18:55 INFO - Running pre-action listener: _resource_record_pre_action 05:18:55 INFO - Running main action method: run_tests 05:18:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:18:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:18:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:18:55 INFO - 2016-05-22 05:18:55.103 screenresolution[1956:29611] starting screenresolution argv=screenresolution get 05:18:55 INFO - 2016-05-22 05:18:55.105 screenresolution[1956:29611] Display 0: 1600x1200x32@60 05:18:55 INFO - 2016-05-22 05:18:55.110 screenresolution[1957:29613] starting screenresolution argv=screenresolution list 05:18:55 INFO - Available Modes on Display 0 05:18:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:18:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:18:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:18:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:18:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:18:55 INFO - Intel Iris: 05:18:55 INFO - Chipset Model: Intel Iris 05:18:55 INFO - Type: GPU 05:18:55 INFO - Bus: Built-In 05:18:55 INFO - VRAM (Dynamic, Max): 1536 MB 05:18:55 INFO - Vendor: Intel (0x8086) 05:18:55 INFO - Device ID: 0x0a2e 05:18:55 INFO - Revision ID: 0x0009 05:18:55 INFO - Displays: 05:18:55 INFO - Display: 05:18:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:18:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:18:55 INFO - Display Serial Number: 1600x1200 60 05:18:55 INFO - Main Display: Yes 05:18:55 INFO - Mirror: Off 05:18:55 INFO - Online: Yes 05:18:55 INFO - Rotation: Supported 05:18:55 INFO - Return code: 0 05:18:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:18:55 INFO - Python 2.7.3 05:18:55 INFO - Return code: 0 05:18:55 INFO - grabbing minidump binary from tooltool 05:18:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:55 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 0x100e17d30>, '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 0x100c3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e0eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:18:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:18:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:18:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:18:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:18:55 INFO - Return code: 0 05:18:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:18:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:18:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:18:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:18:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:18:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:18:56 INFO - Return code: 0 05:18:56 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 05:18:56 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0254 --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 05:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU7aSAOmi9/Render', 05:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:56 INFO - 'HOME': '/Users/cltbld', 05:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:56 INFO - 'LOGNAME': 'cltbld', 05:18:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:18:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:56 INFO - 'MOZ_NO_REMOTE': '1', 05:18:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:18:56 INFO - 'NO_EM_RESTART': '1', 05:18:56 INFO - 'PAGER': '/bin/cat', 05:18:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:56 INFO - 'PWD': '/builds/slave/test', 05:18:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:56 INFO - 'SHELL': '/bin/bash', 05:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8MZVw3TGXk/Listeners', 05:18:56 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 05:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:56 INFO - 'USER': 'cltbld', 05:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:56 INFO - 'XPC_FLAGS': '0x0', 05:18:56 INFO - 'XPC_SERVICE_NAME': '0', 05:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:56 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 05:18:56 INFO - mozversion application_buildid: 20160522030240 05:18:56 INFO - mozversion application_changeset: 16663eb3dcfa759f25b5e27b101bc79270c156f2 05:18:56 INFO - mozversion application_display_name: Nightly 05:18:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:18:56 INFO - mozversion application_name: Firefox 05:18:56 INFO - mozversion application_remotingname: firefox 05:18:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:18:56 INFO - mozversion application_vendor: Mozilla 05:18:56 INFO - mozversion application_version: 49.0a1 05:18:56 INFO - mozversion platform_buildid: 20160522030240 05:18:56 INFO - mozversion platform_changeset: 16663eb3dcfa759f25b5e27b101bc79270c156f2 05:18:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:18:56 INFO - mozversion platform_version: 49.0a1 05:18:56 INFO - using testdate: 1463919536 05:18:56 INFO - actual date: 1463919536 05:18:56 INFO - starting webserver on 'localhost:52755' 05:18:56 INFO - SUITE-START | Running 2 tests 05:18:56 INFO - TEST-START | dromaeo_css 05:18:56 INFO - operating with platform_type : mac_ 05:18:56 INFO - Initialising browser for dromaeo_css test... 05:18:56 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmp7NarSi/profile http://localhost:52755/getInfo.html) 05:18:59 INFO - PROCESS | 1975 | __metrics Screen width/height:1600/1200 05:18:59 INFO - PROCESS | 1975 | colorDepth:24 05:18:59 INFO - PROCESS | 1975 | Browser inner width/height: 1024/689 05:18:59 INFO - PROCESS | 1975 | __metrics 05:18:59 ERROR - PROCESS | 1975 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:19:00 INFO - TEST-INFO | 1975: exit 0 05:19:00 INFO - Browser initialized. 05:19:00 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:00 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmp7NarSi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:12 INFO - PROCESS | 1979 | RSS: Main: 250109952 05:19:12 INFO - PROCESS | 1979 | 05:20:02 INFO - PROCESS | 1979 | 1463919602149 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:22:12 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52755/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52755/tests/dromaeo/cssquery-ext.html) 05:22:13 INFO - PROCESS | 1979 | RSS: Main: 261599232 05:22:13 INFO - PROCESS | 1979 | 05:25:15 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52755/tests/dromaeo/cssquery-ext.html (next: http://localhost:52755/tests/dromaeo/cssquery-jquery.html) 05:25:16 INFO - PROCESS | 1979 | RSS: Main: 273801216 05:25:16 INFO - PROCESS | 1979 | 05:28:18 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52755/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52755/tests/dromaeo/cssquery-mootools.html) 05:28:18 INFO - PROCESS | 1979 | RSS: Main: 275800064 05:28:18 INFO - PROCESS | 1979 | 05:31:20 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52755/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52755/tests/dromaeo/cssquery-prototype.html) 05:31:21 INFO - PROCESS | 1979 | RSS: Main: 277704704 05:31:21 INFO - PROCESS | 1979 | 05:34:24 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52755/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52755/tests/dromaeo/cssquery-yui.html) 05:34:24 INFO - PROCESS | 1979 | RSS: Main: 280363008 05:34:24 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | Cycle 1(1): loaded http://localhost:52755/tests/dromaeo/cssquery-yui.html (next: http://localhost:52755/tests/dromaeo/cssquery-dojo.html) 05:37:27 INFO - PROCESS | 1979 | RSS: Main: 281239552 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | __start_tp_report 05:37:27 INFO - PROCESS | 1979 | _x_x_mozilla_page_load 05:37:27 INFO - PROCESS | 1979 | _x_x_mozilla_page_load_details 05:37:27 INFO - PROCESS | 1979 | |i|pagename|runs| 05:37:27 INFO - PROCESS | 1979 | |0;dojo.html;2907.360380716243;3407.659234076592;3422.127357523831;3480.2517458746374;3489.3893568625485;5156.716380599066;5750.597458177929;5901.911471327928;5921.141434491996;5976.5218782497295;5628.915566266509;5655.604107712462;5724.513416359614;5749.310082790061;5910.556708246877;5061.44324124347;5073.619478539106;5190.818321358754;5191.428942816287;5228.607854410915;305.4656794335347;5196.92204616931;5210.843674689746;5213.947860521384;5266.262723218752;4497.7526236057;5128.102582048145;5251.763670634811;5266.447023062561;5340.54605358545;5163.535281824654;5293.788248470057;5295.735213239361;5331.893362132736;5337.439568845293;4771.451283102453;5027.245913113025;5080.034793389244;5086.059079070347;5109.693418394871;5339.439358867341;5433.375161856358;5476.151196564499;5477.287952566141;5528.281323427969;5079.9840031993745;6111.388861113897;6124;6132.877342453126;6187.597806142631;6185.072239164117;6274.403931626533;6301.054841773725;6302.936970630327;6312.021636646281;5985.551083668699;5997.070454079625;6011.248593925759;6019.789307374221;6065.423784740531;6204.162438070803;6244.843878902973;6269.937300627026;6334.049892516067;6373.490120790326;6266.373362663789;6299.244090709052;6305.905411418832;6310.495160387158;6310.716017779211;6242.15730876335;6255.56211065221;6295.055741638699;6296.150019747367;6306.905396419057;6121.056693972082;6175.320714721377;6227.532935029891;6253.155823963707;6311.337309582428;6105.8843398869;6187.628742275388;6223.502119830403;6253.593516421418;6266.436020758154;5015.3480047593575;5041.269015992734;5050.772715227824;5054.848354549369;5055.064813009605;3787.4697542344093;4446.177457170434;4447.3773671685985;4458.331250312414;4574.794134263966;4914.287428322873;5781.5952883298605;5889.440503152194;5907.409259074144;5969.313528944227;6289.7169627366875;6320.810375688645;6328.873422531524;6330.177076979963;6336.873262534724;4684.367610372624;5780.88438231233;5825.970870145707;5850.3564607893095;5915.438033386821;5467.343918729778;5475;5493.92868390668;5506.531944784738;5519.475649813261;5393.379761327419;6212.81361559154;6424.453921416732;6433.1636887205295;6442.774502892376;6204.751809927733;6209.379062093935;6236.469900058364;6280.717367718372;6294.433500984934;6432.903506447501;6436.388543088587;6454.19322584677;6467.417932386108;6473.320301368289;6135.754569817157;6179.474744646755;6296.874062518816;6324.367563243639;6325.272593651604;6230.6884655767935;6261.405166509175;6294.779682711083;6322.36776322364;6356.77751278703;6241.032639940635;6255.843603909939;6267.874642507216;6279.372062793684;6303.9684801575695;5799.652020878592;5980.820575382745;6109.541784366279;6227.283862355887;6232.688365581794;4729.55069268419;5693.117566777156;5967.552433567413;6030.90953635687;6121.47967422774;6180.046482230763;6180.783672571439;6207.330847735517;6234.875302493834;6239.532035097295;5951.55363347742;5987.371326010706;6015.248093988252;6071.574989750675;6153.66154861469 05:37:27 INFO - PROCESS | 1979 | |1;ext.html;495.99256011159815;518.5074179529447;538.8949154914793;547.4005963469999;559.2673597587161;39329.99999999996;42304.57695423053;42340.57659423401;42348.3647745284;42362.36456453152;37154;42846.14307713851;42882.57117428825;43006.78496607512;43050;9450.480223587718;9661.275404344702;9684.854727179098;9689.903100969006;9718.70843874685;8455.619497122645;9493.81012379752;9525.428474291564;9645.5177241138;9648.131668149865;9563.330566860323;9607.039296070407;9624.518774061304;9658.806823863519;9678.274129440286;8326.500409975391;9475.147236748691;9576.281778866543;9620.615175392975;9651.179649729782;9472.052794720541;9482;9614.23086153113;9626.085521875411;9664.275179361575;40299.395509067384;41196.970075748046;41281.587184128075;41756;41761;2750.559910414344;3044.558539011853;3143.4970404735363;3327.2680010397917;3362.0586235854;26858.73141268582;27154.32114197141;28610.570841437395;28795.99214027499;29567.40865182684;2669.826461280011;2697.3391519077754;2738.0006297701393;2756.0629386008654;2776.41695243999;462452;464127;468839;471055;471226;34507.65492345044;34541.65458345384;35299.4705079424;35333.29333413317;35421.93734187927;34771;35555.466668;35963.640363596554;35981.280374392365;36065;13915.58253252404;13922.164670119826;14215.715685686228;14263.714725705635;14462.20457874806;11975.221610595283;12011.159218854771;12018.519259229708;12025.518979240907;12041;7754.1390066383665;7971.481853679608;7983.640736166886;8041.557714325651;8079.676812927534;2850.7434330909905;3241.25451146233;3271.918202044968;3276.6395696473846;3286.4084464796088;980.4558470049191;1004.6785028790716;1031.2059714020163;1057.360297020307;1086.6631344283298;29321.413571728444;29330;29720.702792972224;29891;30032;1068.903798658109;1098.549594666183;1160.2980196980704;1173.0146676791537;1176.4353110506834;15202.695946081016;15352.232388380537;15480.732072255933;15516.844831551765;15518.146501942276;582773;596862;600328;602259;605380;41354.37968430476;41734;41846.790766045975;42159.36760948588;42208.788956055025;1271.2880786759445;1449.8767604753714;1452.5061479097158;1467.2663668165703;1467.699121680053;23996;28818.71181288244;28964.855175723987;29222;29679.55480667835;50941.745291273306;51566.74216628893;51669.48330516647;51677;51806.48193518168;391411.08588913747;405003;406449;406539;406927;25798.226053217684;26316.605250920868;26514;26694.19917402481;26738.866305668347;45422.99844611808;45663.85840354018;45771;46671.76664116658;47612.2858157121;37634.62365376421;38249.808750956065;38423.80788096042;38531.42202867015;39057;37324.62675373212;37454.25091498209;37659.87020389393;38199.04502387463;38268.42597360986;32567.674323256466;36235.27529449449;37738.62261377461;37766.24467510689;38790;32068.877589284264;34550.85189712315;34733;34848;35061;20241.79758202399;25380.746192537837;25487;25713.871430643474;25940.481190376464 05:37:27 INFO - PROCESS | 1979 | |2;jquery.html;381.7728451571316;382.49892640640763;383.4248627059411;401.1855932457113;406.8331983886607;19234.23063077477;21088.57822843546;22015.889920550413;22077.448063798394;22119.225827096056;11657.358845263507;12242.755144897119;12263.877361226385;21218.257360992367;21713.782862171374;5176.145935920578;5343.011542864563;5375.032494151052;5387.919181212285;5525.281713377255;5854.73653685583;5870.559708021894;5929.673867937262;5942.494887934531;5949.434803693643;4768.926991426936;5259.132243179871;5772.855678608047;5807.274090738658;5912.4974377178;5093.388793344804;5114.156164232897;5149;5151.175811870102;5158.716270605115;4887.266909963499;5014.097462456756;5017.49825017499;5022.573081288095;5239.764210610531;13148.474061037547;13298.335083245798;13866.861331386657;13897.942452660398;25273.747262527322;1979.1984246380096;2051.2615458434952;2114.904829282686;2142.6250406178856;2201.2515744646735;9866.210703143732;10137.39175649456;14215.08096406982;14324.355404006845;15003.849961500464;1689.552268648809;1736.6787144378332;1743.5118166913285;1747.5106970048407;1758.0682238413533;77327;112123;124760.37619811934;125158;125974;9772.21822254218;11321.830172547418;11331.43342832855;11632.302061876315;11698.590549330733;14160.291985400689;14514.20171890535;14517.201553914636;14545.127292362493;14610.196439195734;8238.546879921541;8309.501429914224;8313.792155196048;8376.288015518627;8473.406861519634;8143.429959902868;8210.630521626541;8219.424640275123;8229.177082291722;8346.123657016047;4539.7049191802425;4576.679632425765;4623.491415944243;4663.790129444184;4734.55021772931;1901.8098190180872;1973.2008536542724;1980.227711192636;1981.524434135826;1984.9801501985085;858.4720396955827;897.5736525150501;933.8926023507383;957.6456711016829;972.8248915195333;19255.037248137818;19282.614347713246;19901.60196796085;20144.992750362424;20312.289069882485;897.9551022448982;914.6524320758202;920.2545937790411;988.604558176735;1025.7999690060367;10254.58981640741;10497.895021049844;10566.943305669525;10608.045275925204;10616.94691526553;573058;595869;604708;614030;614591;98562;122401.16398254034;132336;134479.65520344867;136843.31578342043;1001.8597396364515;1006.0995409108807;1010.1312870930993;1024.9641262555924;1027.1525991057408;17025.40411085606;17660.381886633906;17891.73162402539;17993.640127197643;18318.26726930908;53486.73256633692;53787.462125378246;54469.45530544644;54579;54722.452775471735;75561.62219188869;78527;80591.59704201441;81536.18463815523;84560;5779.624324419067;7385.741499047507;7534.623268836426;7534.98277732521;7606.657700403606;12068.879311206776;12716.554920577735;12793.61619151427;13136.211827290394;13250.867491324963;9647.951760241154;9959.402435853872;11260.605878794202;11319.981201691888;11514.251573647685;10063.748406289902;10958.506867191156;11097;11140.777184456427;11195.72010699739;9997.300188986701;11501.079913606723;11550.653480395602;11653.30080195191;11655.76688466243;8134.227248411392;8165.2243036911395;8173.223543763333;8389.916100839158;8539.914600854161;10625.25623206368;10747.301425407324;11105.500252488497;11225.158113141384;11361.829572556584 05:37:27 INFO - PROCESS | 1979 | |3;mootools.html;1537.846215378462;1592.0209071421075;1801.8918864868108;1896.7818700849398;1897.6204759048194;8132;8571.95714021429;8610.88058552388;9055.320850936174;9078.50068246247;8675.175858293453;8736.082711315315;8885.777855553615;8898.644054237839;8913.064128266536;2358.799502042324;2392.091005417939;2397.9040838366445;2399.7600239976036;2400.519896020794;2370.9644355334685;2372.395039264985;2385.9642105368434;2399.2202534176463;2403.0507949359994;2371.27676058802;2378.54807586558;2387.617981123021;2388.486475407787;2400.903963841453;2352.0826877517784;2360.492494113765;2366.0654041653625;2367.112332875172;2374.8693821839793;2352.8353015288935;2363.7518060603734;2369.2300002499255;2370.2652177824757;2384.8569085854906;8813.42712723671;8913.46519208843;8914.019457859631;8920.509371984539;8931.598078086437;5223.425423203445;5282.867928301785;5285.894282114336;5351.411344752074;5373.81191658294;3393;3545.53907991962;3575.964240357589;3579.7673151245085;3584.551931008624;1022.2997246885907;1038.9324693894948;1040.7554224757175;1044.2116202267316;1060.6181774561153;11523.020543253833;11602.361870097142;11659.708507287301;11689.298642081503;11730.648080557597;4504.414426124583;4545.818167273338;4600.6549508786975;4652.651051171176;4723.149833030019;9048.819023619491;9178.816423671622;9195.402298850553;9230.492322922304;9242.768930776785;5206.114960456732;5257.710825904612;5270.235815806686;5279.683219006872;5283.550898173621;4394.187075390999;4745.786439610226;4887.437944636341;4917.852464426073;4928.482509336468;3808.504894363715;3857.4599556062176;3895.6299151580556;3925.3326934421216;3943.585923478051;1179.8643156037165;1180.221054104287;1181.1259667845732;1189.8393716848288;1190.8749581294012;809.2069771623842;856.7643897928119;900.9864852027225;945.5697657565792;950.6910254167424;5163.199704045879;5834.67909264986;5863.794767183128;5914.231149950565;5991.460768530853;778.8052986753312;860.4579115157411;946.2713710442921;959.2134449751277;1019.1948360794889;3741.214344987529;3902.512185976753;3918.7060970427333;3924.9991252230498;3987.063040185554;10427.895721042692;10708.357498550113;10823.729406764895;10831.83752243717;10860.728481788019;10679.893201068046;10821.296615719953;10846.132309415229;10851.186161037796;10874.945625271823;7822.335101516434;8147.714829980804;8251.8349633007;8297.294729947902;8361.874571881426;5865.4134586541;5967.134765458897;5968.313643931004;5981.850453738692;6073.240844894388;9944.701658950244;10138.290319677551;10257.487125643538;10261.640842570474;10339.948300258451;9861.654842080492;10052.949735251277;10345.275830691779;10356.067953884187;10356.223283253636;6951.096357473698;7350.154732205651;7521.849563008818;7610.1628820828555;7631.580263085583;8288.792780180544;8306.584670766559;8412.915870841458;8423.70517031901;8509.446885952393;7373.668184931798;7463.2536746327105;7501.324880760758;7573.659185336784;7618.428617853794;7291.890621640786;7338.266173382619;7350.338469537767;7414.5551266922;7457.813554661177;6965.79102626922;7012.789616311519;7029.297070293134;7055.541389809646;7062.964685176541;4651.279051746985;4759.476457589732;4769.618430525619;4782.3543821585035;4936.407631084293;4838.564529192389;4860.659753817199;4885.951140488692;4932;5077.31456253401 05:37:27 INFO - PROCESS | 1979 | |4;prototype.html;318.6065209466309;318.8979526551503;323.7054280604649;325.47435891035985;330.04123022619297;4797.2324428091515;5235.371755389349;5400.675959442437;5596.804111856086;5677.3187217533905;4665.393498845154;5028.773705183266;5400.405955344909;5500.42245564216;5533.059379905416;3333.300006998521;3665.6151104134146;3934.016495876031;4027.1945610877797;4175.498940127189;3635.818209089548;4100.220958017967;4187.9581204188025;4212.094399704063;4222.809973551198;3760.4735337052834;4010.5387880393837;4092.7339722917914;4205.810738516759;4209.0529630833125;3853.477876238898;4123.309443128303;4135.7932103395015;4229.815651617552;4242.172776308621;3147.9370412591848;3439.7076248518656;3557.02181899977;3570.9642903570893;3580.0691820126954;4858.344123543312;5289.206619007141;5310.468953104659;5337.172738225542;5388.407275199725;4064.979675101635;4629.726825123084;4666.440027196724;4752.881177970544;4780.569748722632;4468.106378724268;5089.796408143669;5510.035743744828;5568.605499635155;5814.389489103625;3063.172943305295;3672.7245456590863;3941.4481972523868;3957.9709275588266;3958.643722064999;5843.766249350021;6114.1745864308195;6125.173101631267;6137.723802428902;6221.413539547387;3963.1875465529497;3993.5806740292433;4001.9785062277792;4010;4017.799110044486;3543.9468407973386;3747.9063023424633;4022.4770779798437;4091.1817636472824;4096.467459230281;4750.548697873696;4783.95216047835;4788.425388953278;4789.30555069513;4829.73436460991;5177.922331165036;5582.218489411485;5614.971925140429;5857.531397488191;5912.881742365129;1212.6059030814845;1304.0089531955782;1387.7571425000585;1397.811295475098;1403.1581051369096;760.8136006678288;819.0253598218102;852.675983126408;860.7632900952289;896.2426749396749;5056.57019153376;5119.950410165907;5180.963807238567;5261.973690131525;5459.044667183227;5302.621318457229;5651.491365777099;5942.821715348547;6000.959673710872;6004.949133901549;4474.463064432289;4653.86038418848;4697.78859951303;5166.560842328444;5421.319390988806;3155.337379150404;3164.5822511687325;3166.1609673436556;3173.9684602503817;3200.815698191684;5146.4853514648985;6063.636181829104;6193.969030154821;6256.278518844227;6282.905756413657;5201.5838732901275;6131.540134489931;6134.539909506805;6163.705621819468;6171.932402645164;5470.398256191816;5686.402927692616;6162.568620196633;6191.25704915413;6230.127782110508;5257.684538927677;5504.972475137599;5583.162525621189;6014.338422773404;6074.149619053424;5330.173823057277;5415.891682166413;5487.862803429946;5856.3558008618165;5888.17565540816;5410.215518749838;5908.911366329595;5981.47761297966;6091;6191.752329906753;3867.149227170018;3918.4710064140977;3948.5459172195565;3964.623360780721;3981.8407263709128;5640.548756099584;5688.971555142198;5865.442782935615;5948.762049517971;5977.312609049841;4501.437320334958;4922.532359425849;5375.92481503693;5815.680137592473;5849.590528663123;4687.718736875799;5187.642380400482;5215.295935048872;5791.884162316815;5842.211301474246;5153.793848246029;5369.624126311121;5464.814074370283;5625.156226566048;5708;5248.923970586097;5734.34055083654;5891.440313170242;5900.734466948912;5900.852478688067;4855.174620314484;5706.514946229617;5740.6268592541355;5797.333226715758;5812.970935145467 05:37:27 INFO - PROCESS | 1979 | |5;yui.html;664.697562609013;673.6867356679144;743.9807463774628;754.6943487887404;766.2452484302961;14737.926310368446;15004.549863504086;15160.317785699643;15223.771643425358;15274.312655930496;12259.693507662312;12473.812892806614;12579.056570757208;12610.306433146174;12756.425960831762;725.3471875312208;906.8050369170627;912.1562554636968;939.8684184214216;940.7271891151591;928.2944961829024;931.5668214280358;933.9813203735955;935.4901578639624;936.8782058332407;915.6657819895756;916.4180745226796;917.1837065012145;958.3004406783052;965.0494263150791;917.0737555069361;924.9213816825578;933.6172169410577;966.5553845231202;967.2793768642372;679.1171477079777;690.8894576867677;719.3094629156017;719.856028794238;720.6288761287941;13943.58169254924;14927.925360373129;15042;15057.774133388008;15106.39574417022;12833.031797566253;12850.485980560767;12872.420741066675;13023.153495022792;13110.213387196703;9311.487868167249;9615.278854085971;9778.168855647278;9927.056929591678;10553.630622928238;3988.4615576897036;3994.582266883108;3995.36074228125;4016.4176194451643;4356.695031347807;64393;82238.58880705558;83946.58026709888;84089.73865391963;84261.31477370419;9213.769655758526;9244.815103697889;9277.539270666211;9322.766930826783;9466.242700583936;19686.114124864416;20446.38660840177;20463.488412789502;20470.692939605917;20480.180792768122;7121.572705637678;7293.781186564412;7307.451941104332;7336.963315183497;7345.77962661121;3966.801659916993;4002.959970400317;4014.6386825185873;4020.718549701493;4027.275090483741;3132.6710695377114;3172.413103575846;3200.791948523385;3205.4390481665614;3206.695363940422;533.2188344075947;617.718937883262;627.6516533324049;632.1245075570368;635.8283263518824;472.8250547297522;523.9973800131052;563.18057226735;578.8118861369987;601.7502736364447;7223.133224013152;7785.766427007199;7940.920590794133;8171.550564718878;8244.422890397735;541.504523361128;557.6458948567689;562.1820251534008;577.8353169346716;602.4397310501275;3779.640934111255;3966.1076257842333;3981.2634662587516;4014.397840323916;4053.2704113259306;75032.87450688244;92186.65300702672;94605.58091628632;94661.58007629891;96620;77767;79646.60176699195;82438.58780706058;82961;84223;47870.56388308356;58128.128078078866;59398.40601594015;65841.67079164478;72487.46293879689;7971.800704982422;8098.676052957816;8117.228863258218;8117.715879944174;8170;40374.990625234605;41071.38392924049;41317.793411032755;42261.78869105635;42719.29122835176;57444;59478;60040.39959600348;60563.091553627615;60605.69697151486;9481.194098501705;10809.297395669257;10957.671269861918;10959.232853700165;11059;11683.299002059903;11833.82249266279;11896.524139034342;12040.698982525157;12060;11174.106071514097;11192.756399094615;11366.659000230007;11513.712157196138;11558.42207889619;10515.369077855354;11147.609833655782;11152.21934464612;11491.827622585835;11554.826677599674;10836;11188.66434006981;11207.607733729606;11602.187846850977;12197.634070977885;9510.619575216913;9576.616935322787;9654.662086827208;9802.460864652512;9981.550830212804;9590.08894155054;9790.706278811647;9794.461304628028;9890.258230632588;9912.504374781376 05:37:27 INFO - PROCESS | 1979 | __end_tp_report 05:37:27 INFO - PROCESS | 1979 | __start_cc_report 05:37:27 INFO - PROCESS | 1979 | _x_x_mozilla_cycle_collect,410 05:37:27 INFO - PROCESS | 1979 | __end_cc_report 05:37:27 INFO - PROCESS | 1979 | __startTimestamp1463920647374__endTimestamp 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | ------- Summary: start ------- 05:37:27 INFO - PROCESS | 1979 | Number of tests: 6 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | [#0] dojo.html Cycles:165 Average:5720.58 Median:6015.25 stddev:812.44 (13.5%) stddev-sans-first:784.39 05:37:27 INFO - PROCESS | 1979 | Values: 2907.4 3407.7 3422.1 3480.3 3489.4 5156.7 5750.6 5901.9 5921.1 5976.5 5628.9 5655.6 5724.5 5749.3 5910.6 5061.4 5073.6 5190.8 5191.4 5228.6 305.5 5196.9 5210.8 5213.9 5266.3 4497.8 5128.1 5251.8 5266.4 5340.5 5163.5 5293.8 5295.7 5331.9 5337.4 4771.5 5027.2 5080.0 5086.1 5109.7 5339.4 5433.4 5476.2 5477.3 5528.3 5080.0 6111.4 6124.0 6132.9 6187.6 6185.1 6274.4 6301.1 6302.9 6312.0 5985.6 5997.1 6011.2 6019.8 6065.4 6204.2 6244.8 6269.9 6334.0 6373.5 6266.4 6299.2 6305.9 6310.5 6310.7 6242.2 6255.6 6295.1 6296.2 6306.9 6121.1 6175.3 6227.5 6253.2 6311.3 6105.9 6187.6 6223.5 6253.6 6266.4 5015.3 5041.3 5050.8 5054.8 5055.1 3787.5 4446.2 4447.4 4458.3 4574.8 4914.3 5781.6 5889.4 5907.4 5969.3 6289.7 6320.8 6328.9 6330.2 6336.9 4684.4 5780.9 5826.0 5850.4 5915.4 5467.3 5475.0 5493.9 5506.5 5519.5 5393.4 6212.8 6424.5 6433.2 6442.8 6204.8 6209.4 6236.5 6280.7 6294.4 6432.9 6436.4 6454.2 6467.4 6473.3 6135.8 6179.5 6296.9 6324.4 6325.3 6230.7 6261.4 6294.8 6322.4 6356.8 6241.0 6255.8 6267.9 6279.4 6304.0 5799.7 5980.8 6109.5 6227.3 6232.7 4729.6 5693.1 5967.6 6030.9 6121.5 6180.0 6180.8 6207.3 6234.9 6239.5 5951.6 5987.4 6015.2 6071.6 6153.7 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | [#1] ext.html Cycles:180 Average:61045.63 Median:37699.25 stddev:132527.25 (351.5%) stddev-sans-first:132821.05 05:37:27 INFO - PROCESS | 1979 | Values: 496.0 518.5 538.9 547.4 559.3 39330.0 42304.6 42340.6 42348.4 42362.4 37154.0 42846.1 42882.6 43006.8 43050.0 9450.5 9661.3 9684.9 9689.9 9718.7 8455.6 9493.8 9525.4 9645.5 9648.1 9563.3 9607.0 9624.5 9658.8 9678.3 8326.5 9475.1 9576.3 9620.6 9651.2 9472.1 9482.0 9614.2 9626.1 9664.3 40299.4 41197.0 41281.6 41756.0 41761.0 2750.6 3044.6 3143.5 3327.3 3362.1 26858.7 27154.3 28610.6 28796.0 29567.4 2669.8 2697.3 2738.0 2756.1 2776.4 462452.0 464127.0 468839.0 471055.0 471226.0 34507.7 34541.7 35299.5 35333.3 35421.9 34771.0 35555.5 35963.6 35981.3 36065.0 13915.6 13922.2 14215.7 14263.7 14462.2 11975.2 12011.2 12018.5 12025.5 12041.0 7754.1 7971.5 7983.6 8041.6 8079.7 2850.7 3241.3 3271.9 3276.6 3286.4 980.5 1004.7 1031.2 1057.4 1086.7 29321.4 29330.0 29720.7 29891.0 30032.0 1068.9 1098.5 1160.3 1173.0 1176.4 15202.7 15352.2 15480.7 15516.8 15518.1 582773.0 596862.0 600328.0 602259.0 605380.0 41354.4 41734.0 41846.8 42159.4 42208.8 1271.3 1449.9 1452.5 1467.3 1467.7 23996.0 28818.7 28964.9 29222.0 29679.6 50941.7 51566.7 51669.5 51677.0 51806.5 391411.1 405003.0 406449.0 406539.0 406927.0 25798.2 26316.6 26514.0 26694.2 26738.9 45423.0 45663.9 45771.0 46671.8 47612.3 37634.6 38249.8 38423.8 38531.4 39057.0 37324.6 37454.3 37659.9 38199.0 38268.4 32567.7 36235.3 37738.6 37766.2 38790.0 32068.9 34550.9 34733.0 34848.0 35061.0 20241.8 25380.7 25487.0 25713.9 25940.5 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | [#2] jquery.html Cycles:180 Average:34489.07 Median:22046.67 stddev:100232.77 (454.6%) stddev-sans-first:100481.23 05:37:27 INFO - PROCESS | 1979 | Values: 381.8 382.5 383.4 401.2 406.8 19234.2 21088.6 22015.9 22077.4 22119.2 11657.4 12242.8 12263.9 21218.3 21713.8 5176.1 5343.0 5375.0 5387.9 5525.3 5854.7 5870.6 5929.7 5942.5 5949.4 4768.9 5259.1 5772.9 5807.3 5912.5 5093.4 5114.2 5149.0 5151.2 5158.7 4887.3 5014.1 5017.5 5022.6 5239.8 13148.5 13298.3 13866.9 13897.9 25273.7 1979.2 2051.3 2114.9 2142.6 2201.3 9866.2 10137.4 14215.1 14324.4 15003.8 1689.6 1736.7 1743.5 1747.5 1758.1 77327.0 112123.0 124760.4 125158.0 125974.0 9772.2 11321.8 11331.4 11632.3 11698.6 14160.3 14514.2 14517.2 14545.1 14610.2 8238.5 8309.5 8313.8 8376.3 8473.4 8143.4 8210.6 8219.4 8229.2 8346.1 4539.7 4576.7 4623.5 4663.8 4734.6 1901.8 1973.2 1980.2 1981.5 1985.0 858.5 897.6 933.9 957.6 972.8 19255.0 19282.6 19901.6 20145.0 20312.3 898.0 914.7 920.3 988.6 1025.8 10254.6 10497.9 10566.9 10608.0 10616.9 573058.0 595869.0 604708.0 614030.0 614591.0 98562.0 122401.2 132336.0 134479.7 136843.3 1001.9 1006.1 1010.1 1025.0 1027.2 17025.4 17660.4 17891.7 17993.6 18318.3 53486.7 53787.5 54469.5 54579.0 54722.5 75561.6 78527.0 80591.6 81536.2 84560.0 5779.6 7385.7 7534.6 7535.0 7606.7 12068.9 12716.6 12793.6 13136.2 13250.9 9648.0 9959.4 11260.6 11320.0 11514.3 10063.7 10958.5 11097.0 11140.8 11195.7 9997.3 11501.1 11550.7 11653.3 11655.8 8134.2 8165.2 8173.2 8389.9 8539.9 10625.3 10747.3 11105.5 11225.2 11361.8 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | [#3] mootools.html Cycles:180 Average:5705.31 Median:4849.61 stddev:3201.24 (66.0%) stddev-sans-first:3194.90 05:37:27 INFO - PROCESS | 1979 | Values: 1537.8 1592.0 1801.9 1896.8 1897.6 8132.0 8572.0 8610.9 9055.3 9078.5 8675.2 8736.1 8885.8 8898.6 8913.1 2358.8 2392.1 2397.9 2399.8 2400.5 2371.0 2372.4 2386.0 2399.2 2403.1 2371.3 2378.5 2387.6 2388.5 2400.9 2352.1 2360.5 2366.1 2367.1 2374.9 2352.8 2363.8 2369.2 2370.3 2384.9 8813.4 8913.5 8914.0 8920.5 8931.6 5223.4 5282.9 5285.9 5351.4 5373.8 3393.0 3545.5 3576.0 3579.8 3584.6 1022.3 1038.9 1040.8 1044.2 1060.6 11523.0 11602.4 11659.7 11689.3 11730.6 4504.4 4545.8 4600.7 4652.7 4723.1 9048.8 9178.8 9195.4 9230.5 9242.8 5206.1 5257.7 5270.2 5279.7 5283.6 4394.2 4745.8 4887.4 4917.9 4928.5 3808.5 3857.5 3895.6 3925.3 3943.6 1179.9 1180.2 1181.1 1189.8 1190.9 809.2 856.8 901.0 945.6 950.7 5163.2 5834.7 5863.8 5914.2 5991.5 778.8 860.5 946.3 959.2 1019.2 3741.2 3902.5 3918.7 3925.0 3987.1 10427.9 10708.4 10823.7 10831.8 10860.7 10679.9 10821.3 10846.1 10851.2 10874.9 7822.3 8147.7 8251.8 8297.3 8361.9 5865.4 5967.1 5968.3 5981.9 6073.2 9944.7 10138.3 10257.5 10261.6 10339.9 9861.7 10052.9 10345.3 10356.1 10356.2 6951.1 7350.2 7521.8 7610.2 7631.6 8288.8 8306.6 8412.9 8423.7 8509.4 7373.7 7463.3 7501.3 7573.7 7618.4 7291.9 7338.3 7350.3 7414.6 7457.8 6965.8 7012.8 7029.3 7055.5 7063.0 4651.3 4759.5 4769.6 4782.4 4936.4 4838.6 4860.7 4886.0 4932.0 5077.3 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | [#4] prototype.html Cycles:180 Average:4643.24 Median:5194.61 stddev:1441.82 (27.8%) stddev-sans-first:1408.86 05:37:27 INFO - PROCESS | 1979 | Values: 318.6 318.9 323.7 325.5 330.0 4797.2 5235.4 5400.7 5596.8 5677.3 4665.4 5028.8 5400.4 5500.4 5533.1 3333.3 3665.6 3934.0 4027.2 4175.5 3635.8 4100.2 4188.0 4212.1 4222.8 3760.5 4010.5 4092.7 4205.8 4209.1 3853.5 4123.3 4135.8 4229.8 4242.2 3147.9 3439.7 3557.0 3571.0 3580.1 4858.3 5289.2 5310.5 5337.2 5388.4 4065.0 4629.7 4666.4 4752.9 4780.6 4468.1 5089.8 5510.0 5568.6 5814.4 3063.2 3672.7 3941.4 3958.0 3958.6 5843.8 6114.2 6125.2 6137.7 6221.4 3963.2 3993.6 4002.0 4010.0 4017.8 3543.9 3747.9 4022.5 4091.2 4096.5 4750.5 4784.0 4788.4 4789.3 4829.7 5177.9 5582.2 5615.0 5857.5 5912.9 1212.6 1304.0 1387.8 1397.8 1403.2 760.8 819.0 852.7 860.8 896.2 5056.6 5120.0 5181.0 5262.0 5459.0 5302.6 5651.5 5942.8 6001.0 6004.9 4474.5 4653.9 4697.8 5166.6 5421.3 3155.3 3164.6 3166.2 3174.0 3200.8 5146.5 6063.6 6194.0 6256.3 6282.9 5201.6 6131.5 6134.5 6163.7 6171.9 5470.4 5686.4 6162.6 6191.3 6230.1 5257.7 5505.0 5583.2 6014.3 6074.1 5330.2 5415.9 5487.9 5856.4 5888.2 5410.2 5908.9 5981.5 6091.0 6191.8 3867.1 3918.5 3948.5 3964.6 3981.8 5640.5 5689.0 5865.4 5948.8 5977.3 4501.4 4922.5 5375.9 5815.7 5849.6 4687.7 5187.6 5215.3 5791.9 5842.2 5153.8 5369.6 5464.8 5625.2 5708.0 5248.9 5734.3 5891.4 5900.7 5900.9 4855.2 5706.5 5740.6 5797.3 5813.0 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - PROCESS | 1979 | [#5] yui.html Cycles:180 Average:17496.35 Median:602.10 stddev:24674.76 (4098.2%) stddev-sans-first:24711.61 05:37:27 INFO - PROCESS | 1979 | Values: 664.7 673.7 744.0 754.7 766.2 14737.9 15004.5 15160.3 15223.8 15274.3 12259.7 12473.8 12579.1 12610.3 12756.4 725.3 906.8 912.2 939.9 940.7 928.3 931.6 934.0 935.5 936.9 915.7 916.4 917.2 958.3 965.0 917.1 924.9 933.6 966.6 967.3 679.1 690.9 719.3 719.9 720.6 13943.6 14927.9 15042.0 15057.8 15106.4 12833.0 12850.5 12872.4 13023.2 13110.2 9311.5 9615.3 9778.2 9927.1 10553.6 3988.5 3994.6 3995.4 4016.4 4356.7 64393.0 82238.6 83946.6 84089.7 84261.3 9213.8 9244.8 9277.5 9322.8 9466.2 19686.1 20446.4 20463.5 20470.7 20480.2 7121.6 7293.8 7307.5 7337.0 7345.8 3966.8 4003.0 4014.6 4020.7 4027.3 3132.7 3172.4 3200.8 3205.4 3206.7 533.2 617.7 627.7 632.1 635.8 472.8 524.0 563.2 578.8 601.8 7223.1 7785.8 7940.9 8171.6 8244.4 541.5 557.6 562.2 577.8 602.4 3779.6 3966.1 3981.3 4014.4 4053.3 75032.9 92186.7 94605.6 94661.6 96620.0 77767.0 79646.6 82438.6 82961.0 84223.0 47870.6 58128.1 59398.4 65841.7 72487.5 7971.8 8098.7 8117.2 8117.7 8170.0 40375.0 41071.4 41317.8 42261.8 42719.3 57444.0 59478.0 60040.4 60563.1 60605.7 9481.2 10809.3 10957.7 10959.2 11059.0 11683.3 11833.8 11896.5 12040.7 12060.0 11174.1 11192.8 11366.7 11513.7 11558.4 10515.4 11147.6 11152.2 11491.8 11554.8 10836.0 11188.7 11207.6 11602.2 12197.6 9510.6 9576.6 9654.7 9802.5 9981.6 9590.1 9790.7 9794.5 9890.3 9912.5 05:37:27 INFO - PROCESS | 1979 | -------- Summary: end -------- 05:37:27 INFO - PROCESS | 1979 | 05:37:27 INFO - TEST-INFO | 1979: exit 0 05:37:27 INFO - TEST-OK | dromaeo_css | took 1111121ms 05:37:27 INFO - TEST-START | kraken 05:37:27 INFO - operating with platform_type : mac_ 05:37:27 INFO - Initialising browser for kraken test... 05:37:27 INFO - TEST-INFO | started process 1985 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpto1gaW/profile http://localhost:52755/getInfo.html) 05:37:30 INFO - PROCESS | 1985 | __metrics Screen width/height:1600/1200 05:37:30 INFO - PROCESS | 1985 | colorDepth:24 05:37:30 INFO - PROCESS | 1985 | Browser inner width/height: 1024/689 05:37:30 INFO - PROCESS | 1985 | __metrics 05:37:30 ERROR - PROCESS | 1985 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:37:31 INFO - TEST-INFO | 1985: exit 0 05:37:31 INFO - Browser initialized. 05:37:31 INFO - Running cycle 1/1 for kraken test... 05:37:31 INFO - TEST-INFO | started process 1989 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpto1gaW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:43 INFO - PROCESS | 1989 | RSS: Main: 249917440 05:37:43 INFO - PROCESS | 1989 | 05:37:46 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52755/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:47 INFO - PROCESS | 1989 | RSS: Main: 261988352 05:37:47 INFO - PROCESS | 1989 | 05:37:50 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52755/tests/kraken/driver.html?testName=audio-dft) 05:37:50 INFO - PROCESS | 1989 | RSS: Main: 266661888 05:37:50 INFO - PROCESS | 1989 | 05:37:54 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52755/tests/kraken/driver.html?testName=audio-fft) 05:37:54 INFO - PROCESS | 1989 | RSS: Main: 266997760 05:37:54 INFO - PROCESS | 1989 | 05:37:56 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52755/tests/kraken/driver.html?testName=audio-oscillator) 05:37:57 INFO - PROCESS | 1989 | RSS: Main: 267591680 05:37:57 INFO - PROCESS | 1989 | 05:37:59 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52755/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:00 INFO - PROCESS | 1989 | RSS: Main: 268247040 05:38:00 INFO - PROCESS | 1989 | 05:38:03 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52755/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:03 INFO - PROCESS | 1989 | RSS: Main: 268763136 05:38:03 INFO - PROCESS | 1989 | 05:38:07 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52755/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:07 INFO - PROCESS | 1989 | RSS: Main: 269438976 05:38:07 INFO - PROCESS | 1989 | 05:38:10 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52755/tests/kraken/driver.html?testName=json-parse-financial) 05:38:11 INFO - PROCESS | 1989 | RSS: Main: 269230080 05:38:11 INFO - PROCESS | 1989 | 05:38:12 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52755/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:38:13 INFO - PROCESS | 1989 | RSS: Main: 268701696 05:38:13 INFO - PROCESS | 1989 | 05:38:15 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52755/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:38:16 INFO - PROCESS | 1989 | RSS: Main: 268603392 05:38:16 INFO - PROCESS | 1989 | 05:38:18 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52755/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:38:19 INFO - PROCESS | 1989 | RSS: Main: 268959744 05:38:19 INFO - PROCESS | 1989 | 05:38:22 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52755/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:22 INFO - PROCESS | 1989 | RSS: Main: 268865536 05:38:22 INFO - PROCESS | 1989 | 05:38:25 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52755/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:26 INFO - PROCESS | 1989 | RSS: Main: 268840960 05:38:26 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52755/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52755/tests/kraken/driver.html?testName=ai-astar) 05:38:28 INFO - PROCESS | 1989 | RSS: Main: 270942208 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | __start_tp_report 05:38:28 INFO - PROCESS | 1989 | _x_x_mozilla_page_load 05:38:28 INFO - PROCESS | 1989 | _x_x_mozilla_page_load_details 05:38:28 INFO - PROCESS | 1989 | |i|pagename|runs| 05:38:28 INFO - PROCESS | 1989 | |0;ai-astar;115;112;116;115;119;165;126;116;117;113 05:38:28 INFO - PROCESS | 1989 | |1;audio-beat-detection;175;127;129;232;107;166;160;139;175;122 05:38:28 INFO - PROCESS | 1989 | |2;audio-dft;196;201;196;220;261;274;265;194;194;197 05:38:28 INFO - PROCESS | 1989 | |3;audio-fft;72;82;76;173;58;59;75;89;133;56 05:38:28 INFO - PROCESS | 1989 | |4;audio-oscillator;80;80;149;79;82;80;121;78;82;122 05:38:28 INFO - PROCESS | 1989 | |5;imaging-gaussian-blur;87;85;87;84;88;85;87;89;88;85 05:38:28 INFO - PROCESS | 1989 | |6;imaging-darkroom;123;147;138;95;122;148;95;95;94;144 05:38:28 INFO - PROCESS | 1989 | |7;imaging-desaturate;90;90;90;90;89;91;91;89;90;91 05:38:28 INFO - PROCESS | 1989 | |8;json-parse-financial;59;59;58;59;59;59;56;118;58;56 05:38:28 INFO - PROCESS | 1989 | |9;json-stringify-tinderbox;67;69;75;95;65;89;60;59;58;59 05:38:28 INFO - PROCESS | 1989 | |10;stanford-crypto-aes;89;104;84;85;87;86;84;90;90;105 05:38:28 INFO - PROCESS | 1989 | |11;stanford-crypto-ccm;140;136;146;142;137;122;129;179;233;154 05:38:28 INFO - PROCESS | 1989 | |12;stanford-crypto-pbkdf2;167;168;164;168;164;164;172;193;153;158 05:38:28 INFO - PROCESS | 1989 | |13;stanford-crypto-sha256-iterative;62;77;86;85;83;77;64;65;65;67 05:38:28 INFO - PROCESS | 1989 | __end_tp_report 05:38:28 INFO - PROCESS | 1989 | __start_cc_report 05:38:28 INFO - PROCESS | 1989 | _x_x_mozilla_cycle_collect,1073 05:38:28 INFO - PROCESS | 1989 | __end_cc_report 05:38:28 INFO - PROCESS | 1989 | __startTimestamp1463920708354__endTimestamp 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | ------- Summary: start ------- 05:38:28 INFO - PROCESS | 1989 | Number of tests: 14 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#0] ai-astar Cycles:10 Average:121.40 Median:116.50 stddev:15.80 (13.6%) stddev-sans-first:16.59 05:38:28 INFO - PROCESS | 1989 | Values: 115.0 112.0 116.0 115.0 119.0 165.0 126.0 116.0 117.0 113.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#1] audio-beat-detection Cycles:10 Average:153.20 Median:163.00 stddev:36.44 (22.4%) stddev-sans-first:37.79 05:38:28 INFO - PROCESS | 1989 | Values: 175.0 127.0 129.0 232.0 107.0 166.0 160.0 139.0 175.0 122.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#2] audio-dft Cycles:10 Average:219.80 Median:210.50 stddev:33.36 (15.8%) stddev-sans-first:34.25 05:38:28 INFO - PROCESS | 1989 | Values: 196.0 201.0 196.0 220.0 261.0 274.0 265.0 194.0 194.0 197.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#3] audio-fft Cycles:10 Average:87.30 Median:73.50 stddev:37.44 (50.9%) stddev-sans-first:39.30 05:38:28 INFO - PROCESS | 1989 | Values: 72.0 82.0 76.0 173.0 58.0 59.0 75.0 89.0 133.0 56.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:80.00 stddev:25.56 (31.9%) stddev-sans-first:26.50 05:38:28 INFO - PROCESS | 1989 | Values: 80.0 80.0 149.0 79.0 82.0 80.0 121.0 78.0 82.0 122.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:1.65 (1.9%) stddev-sans-first:1.74 05:38:28 INFO - PROCESS | 1989 | Values: 87.0 85.0 87.0 84.0 88.0 85.0 87.0 89.0 88.0 85.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#6] imaging-darkroom Cycles:10 Average:120.10 Median:121.00 stddev:23.52 (19.4%) stddev-sans-first:24.92 05:38:28 INFO - PROCESS | 1989 | Values: 123.0 147.0 138.0 95.0 122.0 148.0 95.0 95.0 94.0 144.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#7] imaging-desaturate Cycles:10 Average:90.10 Median:90.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 05:38:28 INFO - PROCESS | 1989 | Values: 90.0 90.0 90.0 90.0 89.0 91.0 91.0 89.0 90.0 91.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:59.00 stddev:18.98 (32.2%) stddev-sans-first:20.04 05:38:28 INFO - PROCESS | 1989 | Values: 59.0 59.0 58.0 59.0 59.0 59.0 56.0 118.0 58.0 56.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:68.00 stddev:13.04 (19.2%) stddev-sans-first:13.80 05:38:28 INFO - PROCESS | 1989 | Values: 67.0 69.0 75.0 95.0 65.0 89.0 60.0 59.0 58.0 59.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:86.50 stddev:7.76 (9.0%) stddev-sans-first:8.22 05:38:28 INFO - PROCESS | 1989 | Values: 89.0 104.0 84.0 85.0 87.0 86.0 84.0 90.0 90.0 105.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:144.00 stddev:32.46 (22.5%) stddev-sans-first:34.15 05:38:28 INFO - PROCESS | 1989 | Values: 140.0 136.0 146.0 142.0 137.0 122.0 129.0 179.0 233.0 154.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:167.50 stddev:10.58 (6.3%) stddev-sans-first:11.22 05:38:28 INFO - PROCESS | 1989 | Values: 167.0 168.0 164.0 168.0 164.0 164.0 172.0 193.0 153.0 158.0 05:38:28 INFO - PROCESS | 1989 | 05:38:28 INFO - PROCESS | 1989 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:77.00 stddev:9.49 (12.3%) stddev-sans-first:9.18 05:38:28 INFO - PROCESS | 1989 | Values: 62.0 77.0 86.0 85.0 83.0 77.0 64.0 65.0 65.0 67.0 05:38:28 INFO - PROCESS | 1989 | -------- Summary: end -------- 05:38:28 INFO - PROCESS | 1989 | 05:38:29 INFO - TEST-INFO | 1989: exit 0 05:38:29 INFO - TEST-OK | kraken | took 61406ms 05:38:29 INFO - SUITE-END | took 1172s 05:38:29 INFO - Completed test suite (00:19:32) 05:38:29 INFO - javascript benchmark 05:38:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7030.4435246644025, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2907.360380716243, 3407.659234076592, 3422.127357523831, 3480.2517458746374, 3489.3893568625485, 5156.716380599066, 5750.597458177929, 5901.911471327928, 5921.141434491996, 5976.5218782497295, 5628.915566266509, 5655.604107712462, 5724.513416359614, 5749.310082790061, 5910.556708246877, 5061.44324124347, 5073.619478539106, 5190.818321358754, 5191.428942816287, 5228.607854410915, 305.4656794335347, 5196.92204616931, 5210.843674689746, 5213.947860521384, 5266.262723218752, 4497.7526236057, 5128.102582048145, 5251.763670634811, 5266.447023062561, 5340.54605358545, 5163.535281824654, 5293.788248470057, 5295.735213239361, 5331.893362132736, 5337.439568845293, 4771.451283102453, 5027.245913113025, 5080.034793389244, 5086.059079070347, 5109.693418394871, 5339.439358867341, 5433.375161856358, 5476.151196564499, 5477.287952566141, 5528.281323427969, 5079.9840031993745, 6111.388861113897, 6124.0, 6132.877342453126, 6187.597806142631, 6185.072239164117, 6274.403931626533, 6301.054841773725, 6302.936970630327, 6312.021636646281, 5985.551083668699, 5997.070454079625, 6011.248593925759, 6019.789307374221, 6065.423784740531, 6204.162438070803, 6244.843878902973, 6269.937300627026, 6334.049892516067, 6373.490120790326, 6266.373362663789, 6299.244090709052, 6305.905411418832, 6310.495160387158, 6310.716017779211, 6242.15730876335, 6255.56211065221, 6295.055741638699, 6296.150019747367, 6306.905396419057, 6121.056693972082, 6175.320714721377, 6227.532935029891, 6253.155823963707, 6311.337309582428, 6105.8843398869, 6187.628742275388, 6223.502119830403, 6253.593516421418, 6266.436020758154, 5015.3480047593575, 5041.269015992734, 5050.772715227824, 5054.848354549369, 5055.064813009605, 3787.4697542344093, 4446.177457170434, 4447.3773671685985, 4458.331250312414, 4574.794134263966, 4914.287428322873, 5781.5952883298605, 5889.440503152194, 5907.409259074144, 5969.313528944227, 6289.7169627366875, 6320.810375688645, 6328.873422531524, 6330.177076979963, 6336.873262534724, 4684.367610372624, 5780.88438231233, 5825.970870145707, 5850.3564607893095, 5915.438033386821, 5467.343918729778, 5475.0, 5493.92868390668, 5506.531944784738, 5519.475649813261, 5393.379761327419, 6212.81361559154, 6424.453921416732, 6433.1636887205295, 6442.774502892376, 6204.751809927733, 6209.379062093935, 6236.469900058364, 6280.717367718372, 6294.433500984934, 6432.903506447501, 6436.388543088587, 6454.19322584677, 6467.417932386108, 6473.320301368289, 6135.754569817157, 6179.474744646755, 6296.874062518816, 6324.367563243639, 6325.272593651604, 6230.6884655767935, 6261.405166509175, 6294.779682711083, 6322.36776322364, 6356.77751278703, 6241.032639940635, 6255.843603909939, 6267.874642507216, 6279.372062793684, 6303.9684801575695, 5799.652020878592, 5980.820575382745, 6109.541784366279, 6227.283862355887, 6232.688365581794, 4729.55069268419, 5693.117566777156, 5967.552433567413, 6030.90953635687, 6121.47967422774, 6180.046482230763, 6180.783672571439, 6207.330847735517, 6234.875302493834, 6239.532035097295, 5951.55363347742, 5987.371326010706, 6015.248093988252, 6071.574989750675, 6153.66154861469], "value": 5669.35120526666, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.99256011159815, 518.5074179529447, 538.8949154914793, 547.4005963469999, 559.2673597587161, 39329.99999999996, 42304.57695423053, 42340.57659423401, 42348.3647745284, 42362.36456453152, 37154.0, 42846.14307713851, 42882.57117428825, 43006.78496607512, 43050.0, 9450.480223587718, 9661.275404344702, 9684.854727179098, 9689.903100969006, 9718.70843874685, 8455.619497122645, 9493.81012379752, 9525.428474291564, 9645.5177241138, 9648.131668149865, 9563.330566860323, 9607.039296070407, 9624.518774061304, 9658.806823863519, 9678.274129440286, 8326.500409975391, 9475.147236748691, 9576.281778866543, 9620.615175392975, 9651.179649729782, 9472.052794720541, 9482.0, 9614.23086153113, 9626.085521875411, 9664.275179361575, 40299.395509067384, 41196.970075748046, 41281.587184128075, 41756.0, 41761.0, 2750.559910414344, 3044.558539011853, 3143.4970404735363, 3327.2680010397917, 3362.0586235854, 26858.73141268582, 27154.32114197141, 28610.570841437395, 28795.99214027499, 29567.40865182684, 2669.826461280011, 2697.3391519077754, 2738.0006297701393, 2756.0629386008654, 2776.41695243999, 462452.0, 464127.0, 468839.0, 471055.0, 471226.0, 34507.65492345044, 34541.65458345384, 35299.4705079424, 35333.29333413317, 35421.93734187927, 34771.0, 35555.466668, 35963.640363596554, 35981.280374392365, 36065.0, 13915.58253252404, 13922.164670119826, 14215.715685686228, 14263.714725705635, 14462.20457874806, 11975.221610595283, 12011.159218854771, 12018.519259229708, 12025.518979240907, 12041.0, 7754.1390066383665, 7971.481853679608, 7983.640736166886, 8041.557714325651, 8079.676812927534, 2850.7434330909905, 3241.25451146233, 3271.918202044968, 3276.6395696473846, 3286.4084464796088, 980.4558470049191, 1004.6785028790716, 1031.2059714020163, 1057.360297020307, 1086.6631344283298, 29321.413571728444, 29330.0, 29720.702792972224, 29891.0, 30032.0, 1068.903798658109, 1098.549594666183, 1160.2980196980704, 1173.0146676791537, 1176.4353110506834, 15202.695946081016, 15352.232388380537, 15480.732072255933, 15516.844831551765, 15518.146501942276, 582773.0, 596862.0, 600328.0, 602259.0, 605380.0, 41354.37968430476, 41734.0, 41846.790766045975, 42159.36760948588, 42208.788956055025, 1271.2880786759445, 1449.8767604753714, 1452.5061479097158, 1467.2663668165703, 1467.699121680053, 23996.0, 28818.71181288244, 28964.855175723987, 29222.0, 29679.55480667835, 50941.745291273306, 51566.74216628893, 51669.48330516647, 51677.0, 51806.48193518168, 391411.08588913747, 405003.0, 406449.0, 406539.0, 406927.0, 25798.226053217684, 26316.605250920868, 26514.0, 26694.19917402481, 26738.866305668347, 45422.99844611808, 45663.85840354018, 45771.0, 46671.76664116658, 47612.2858157121, 37634.62365376421, 38249.808750956065, 38423.80788096042, 38531.42202867015, 39057.0, 37324.62675373212, 37454.25091498209, 37659.87020389393, 38199.04502387463, 38268.42597360986, 32567.674323256466, 36235.27529449449, 37738.62261377461, 37766.24467510689, 38790.0, 32068.877589284264, 34550.85189712315, 34733.0, 34848.0, 35061.0, 20241.79758202399, 25380.746192537837, 25487.0, 25713.871430643474, 25940.481190376464], "value": 17828.750479649912, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.7728451571316, 382.49892640640763, 383.4248627059411, 401.1855932457113, 406.8331983886607, 19234.23063077477, 21088.57822843546, 22015.889920550413, 22077.448063798394, 22119.225827096056, 11657.358845263507, 12242.755144897119, 12263.877361226385, 21218.257360992367, 21713.782862171374, 5176.145935920578, 5343.011542864563, 5375.032494151052, 5387.919181212285, 5525.281713377255, 5854.73653685583, 5870.559708021894, 5929.673867937262, 5942.494887934531, 5949.434803693643, 4768.926991426936, 5259.132243179871, 5772.855678608047, 5807.274090738658, 5912.4974377178, 5093.388793344804, 5114.156164232897, 5149.0, 5151.175811870102, 5158.716270605115, 4887.266909963499, 5014.097462456756, 5017.49825017499, 5022.573081288095, 5239.764210610531, 13148.474061037547, 13298.335083245798, 13866.861331386657, 13897.942452660398, 25273.747262527322, 1979.1984246380096, 2051.2615458434952, 2114.904829282686, 2142.6250406178856, 2201.2515744646735, 9866.210703143732, 10137.39175649456, 14215.08096406982, 14324.355404006845, 15003.849961500464, 1689.552268648809, 1736.6787144378332, 1743.5118166913285, 1747.5106970048407, 1758.0682238413533, 77327.0, 112123.0, 124760.37619811934, 125158.0, 125974.0, 9772.21822254218, 11321.830172547418, 11331.43342832855, 11632.302061876315, 11698.590549330733, 14160.291985400689, 14514.20171890535, 14517.201553914636, 14545.127292362493, 14610.196439195734, 8238.546879921541, 8309.501429914224, 8313.792155196048, 8376.288015518627, 8473.406861519634, 8143.429959902868, 8210.630521626541, 8219.424640275123, 8229.177082291722, 8346.123657016047, 4539.7049191802425, 4576.679632425765, 4623.491415944243, 4663.790129444184, 4734.55021772931, 1901.8098190180872, 1973.2008536542724, 1980.227711192636, 1981.524434135826, 1984.9801501985085, 858.4720396955827, 897.5736525150501, 933.8926023507383, 957.6456711016829, 972.8248915195333, 19255.037248137818, 19282.614347713246, 19901.60196796085, 20144.992750362424, 20312.289069882485, 897.9551022448982, 914.6524320758202, 920.2545937790411, 988.604558176735, 1025.7999690060367, 10254.58981640741, 10497.895021049844, 10566.943305669525, 10608.045275925204, 10616.94691526553, 573058.0, 595869.0, 604708.0, 614030.0, 614591.0, 98562.0, 122401.16398254034, 132336.0, 134479.65520344867, 136843.31578342043, 1001.8597396364515, 1006.0995409108807, 1010.1312870930993, 1024.9641262555924, 1027.1525991057408, 17025.40411085606, 17660.381886633906, 17891.73162402539, 17993.640127197643, 18318.26726930908, 53486.73256633692, 53787.462125378246, 54469.45530544644, 54579.0, 54722.452775471735, 75561.62219188869, 78527.0, 80591.59704201441, 81536.18463815523, 84560.0, 5779.624324419067, 7385.741499047507, 7534.623268836426, 7534.98277732521, 7606.657700403606, 12068.879311206776, 12716.554920577735, 12793.61619151427, 13136.211827290394, 13250.867491324963, 9647.951760241154, 9959.402435853872, 11260.605878794202, 11319.981201691888, 11514.251573647685, 10063.748406289902, 10958.506867191156, 11097.0, 11140.777184456427, 11195.72010699739, 9997.300188986701, 11501.079913606723, 11550.653480395602, 11653.30080195191, 11655.76688466243, 8134.227248411392, 8165.2243036911395, 8173.223543763333, 8389.916100839158, 8539.914600854161, 10625.25623206368, 10747.301425407324, 11105.500252488497, 11225.158113141384, 11361.829572556584], "value": 9244.65570318409, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.846215378462, 1592.0209071421075, 1801.8918864868108, 1896.7818700849398, 1897.6204759048194, 8132.0, 8571.95714021429, 8610.88058552388, 9055.320850936174, 9078.50068246247, 8675.175858293453, 8736.082711315315, 8885.777855553615, 8898.644054237839, 8913.064128266536, 2358.799502042324, 2392.091005417939, 2397.9040838366445, 2399.7600239976036, 2400.519896020794, 2370.9644355334685, 2372.395039264985, 2385.9642105368434, 2399.2202534176463, 2403.0507949359994, 2371.27676058802, 2378.54807586558, 2387.617981123021, 2388.486475407787, 2400.903963841453, 2352.0826877517784, 2360.492494113765, 2366.0654041653625, 2367.112332875172, 2374.8693821839793, 2352.8353015288935, 2363.7518060603734, 2369.2300002499255, 2370.2652177824757, 2384.8569085854906, 8813.42712723671, 8913.46519208843, 8914.019457859631, 8920.509371984539, 8931.598078086437, 5223.425423203445, 5282.867928301785, 5285.894282114336, 5351.411344752074, 5373.81191658294, 3393.0, 3545.53907991962, 3575.964240357589, 3579.7673151245085, 3584.551931008624, 1022.2997246885907, 1038.9324693894948, 1040.7554224757175, 1044.2116202267316, 1060.6181774561153, 11523.020543253833, 11602.361870097142, 11659.708507287301, 11689.298642081503, 11730.648080557597, 4504.414426124583, 4545.818167273338, 4600.6549508786975, 4652.651051171176, 4723.149833030019, 9048.819023619491, 9178.816423671622, 9195.402298850553, 9230.492322922304, 9242.768930776785, 5206.114960456732, 5257.710825904612, 5270.235815806686, 5279.683219006872, 5283.550898173621, 4394.187075390999, 4745.786439610226, 4887.437944636341, 4917.852464426073, 4928.482509336468, 3808.504894363715, 3857.4599556062176, 3895.6299151580556, 3925.3326934421216, 3943.585923478051, 1179.8643156037165, 1180.221054104287, 1181.1259667845732, 1189.8393716848288, 1190.8749581294012, 809.2069771623842, 856.7643897928119, 900.9864852027225, 945.5697657565792, 950.6910254167424, 5163.199704045879, 5834.67909264986, 5863.794767183128, 5914.231149950565, 5991.460768530853, 778.8052986753312, 860.4579115157411, 946.2713710442921, 959.2134449751277, 1019.1948360794889, 3741.214344987529, 3902.512185976753, 3918.7060970427333, 3924.9991252230498, 3987.063040185554, 10427.895721042692, 10708.357498550113, 10823.729406764895, 10831.83752243717, 10860.728481788019, 10679.893201068046, 10821.296615719953, 10846.132309415229, 10851.186161037796, 10874.945625271823, 7822.335101516434, 8147.714829980804, 8251.8349633007, 8297.294729947902, 8361.874571881426, 5865.4134586541, 5967.134765458897, 5968.313643931004, 5981.850453738692, 6073.240844894388, 9944.701658950244, 10138.290319677551, 10257.487125643538, 10261.640842570474, 10339.948300258451, 9861.654842080492, 10052.949735251277, 10345.275830691779, 10356.067953884187, 10356.223283253636, 6951.096357473698, 7350.154732205651, 7521.849563008818, 7610.1628820828555, 7631.580263085583, 8288.792780180544, 8306.584670766559, 8412.915870841458, 8423.70517031901, 8509.446885952393, 7373.668184931798, 7463.2536746327105, 7501.324880760758, 7573.659185336784, 7618.428617853794, 7291.890621640786, 7338.266173382619, 7350.338469537767, 7414.5551266922, 7457.813554661177, 6965.79102626922, 7012.789616311519, 7029.297070293134, 7055.541389809646, 7062.964685176541, 4651.279051746985, 4759.476457589732, 4769.618430525619, 4782.3543821585035, 4936.407631084293, 4838.564529192389, 4860.659753817199, 4885.951140488692, 4932.0, 5077.31456253401], "value": 4579.674815109827, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.6065209466309, 318.8979526551503, 323.7054280604649, 325.47435891035985, 330.04123022619297, 4797.2324428091515, 5235.371755389349, 5400.675959442437, 5596.804111856086, 5677.3187217533905, 4665.393498845154, 5028.773705183266, 5400.405955344909, 5500.42245564216, 5533.059379905416, 3333.300006998521, 3665.6151104134146, 3934.016495876031, 4027.1945610877797, 4175.498940127189, 3635.818209089548, 4100.220958017967, 4187.9581204188025, 4212.094399704063, 4222.809973551198, 3760.4735337052834, 4010.5387880393837, 4092.7339722917914, 4205.810738516759, 4209.0529630833125, 3853.477876238898, 4123.309443128303, 4135.7932103395015, 4229.815651617552, 4242.172776308621, 3147.9370412591848, 3439.7076248518656, 3557.02181899977, 3570.9642903570893, 3580.0691820126954, 4858.344123543312, 5289.206619007141, 5310.468953104659, 5337.172738225542, 5388.407275199725, 4064.979675101635, 4629.726825123084, 4666.440027196724, 4752.881177970544, 4780.569748722632, 4468.106378724268, 5089.796408143669, 5510.035743744828, 5568.605499635155, 5814.389489103625, 3063.172943305295, 3672.7245456590863, 3941.4481972523868, 3957.9709275588266, 3958.643722064999, 5843.766249350021, 6114.1745864308195, 6125.173101631267, 6137.723802428902, 6221.413539547387, 3963.1875465529497, 3993.5806740292433, 4001.9785062277792, 4010.0, 4017.799110044486, 3543.9468407973386, 3747.9063023424633, 4022.4770779798437, 4091.1817636472824, 4096.467459230281, 4750.548697873696, 4783.95216047835, 4788.425388953278, 4789.30555069513, 4829.73436460991, 5177.922331165036, 5582.218489411485, 5614.971925140429, 5857.531397488191, 5912.881742365129, 1212.6059030814845, 1304.0089531955782, 1387.7571425000585, 1397.811295475098, 1403.1581051369096, 760.8136006678288, 819.0253598218102, 852.675983126408, 860.7632900952289, 896.2426749396749, 5056.57019153376, 5119.950410165907, 5180.963807238567, 5261.973690131525, 5459.044667183227, 5302.621318457229, 5651.491365777099, 5942.821715348547, 6000.959673710872, 6004.949133901549, 4474.463064432289, 4653.86038418848, 4697.78859951303, 5166.560842328444, 5421.319390988806, 3155.337379150404, 3164.5822511687325, 3166.1609673436556, 3173.9684602503817, 3200.815698191684, 5146.4853514648985, 6063.636181829104, 6193.969030154821, 6256.278518844227, 6282.905756413657, 5201.5838732901275, 6131.540134489931, 6134.539909506805, 6163.705621819468, 6171.932402645164, 5470.398256191816, 5686.402927692616, 6162.568620196633, 6191.25704915413, 6230.127782110508, 5257.684538927677, 5504.972475137599, 5583.162525621189, 6014.338422773404, 6074.149619053424, 5330.173823057277, 5415.891682166413, 5487.862803429946, 5856.3558008618165, 5888.17565540816, 5410.215518749838, 5908.911366329595, 5981.47761297966, 6091.0, 6191.752329906753, 3867.149227170018, 3918.4710064140977, 3948.5459172195565, 3964.623360780721, 3981.8407263709128, 5640.548756099584, 5688.971555142198, 5865.442782935615, 5948.762049517971, 5977.312609049841, 4501.437320334958, 4922.532359425849, 5375.92481503693, 5815.680137592473, 5849.590528663123, 4687.718736875799, 5187.642380400482, 5215.295935048872, 5791.884162316815, 5842.211301474246, 5153.793848246029, 5369.624126311121, 5464.814074370283, 5625.156226566048, 5708.0, 5248.923970586097, 5734.34055083654, 5891.440313170242, 5900.734466948912, 5900.852478688067, 4855.174620314484, 5706.514946229617, 5740.6268592541355, 5797.333226715758, 5812.970935145467], "value": 4181.909282946931, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.697562609013, 673.6867356679144, 743.9807463774628, 754.6943487887404, 766.2452484302961, 14737.926310368446, 15004.549863504086, 15160.317785699643, 15223.771643425358, 15274.312655930496, 12259.693507662312, 12473.812892806614, 12579.056570757208, 12610.306433146174, 12756.425960831762, 725.3471875312208, 906.8050369170627, 912.1562554636968, 939.8684184214216, 940.7271891151591, 928.2944961829024, 931.5668214280358, 933.9813203735955, 935.4901578639624, 936.8782058332407, 915.6657819895756, 916.4180745226796, 917.1837065012145, 958.3004406783052, 965.0494263150791, 917.0737555069361, 924.9213816825578, 933.6172169410577, 966.5553845231202, 967.2793768642372, 679.1171477079777, 690.8894576867677, 719.3094629156017, 719.856028794238, 720.6288761287941, 13943.58169254924, 14927.925360373129, 15042.0, 15057.774133388008, 15106.39574417022, 12833.031797566253, 12850.485980560767, 12872.420741066675, 13023.153495022792, 13110.213387196703, 9311.487868167249, 9615.278854085971, 9778.168855647278, 9927.056929591678, 10553.630622928238, 3988.4615576897036, 3994.582266883108, 3995.36074228125, 4016.4176194451643, 4356.695031347807, 64393.0, 82238.58880705558, 83946.58026709888, 84089.73865391963, 84261.31477370419, 9213.769655758526, 9244.815103697889, 9277.539270666211, 9322.766930826783, 9466.242700583936, 19686.114124864416, 20446.38660840177, 20463.488412789502, 20470.692939605917, 20480.180792768122, 7121.572705637678, 7293.781186564412, 7307.451941104332, 7336.963315183497, 7345.77962661121, 3966.801659916993, 4002.959970400317, 4014.6386825185873, 4020.718549701493, 4027.275090483741, 3132.6710695377114, 3172.413103575846, 3200.791948523385, 3205.4390481665614, 3206.695363940422, 533.2188344075947, 617.718937883262, 627.6516533324049, 632.1245075570368, 635.8283263518824, 472.8250547297522, 523.9973800131052, 563.18057226735, 578.8118861369987, 601.7502736364447, 7223.133224013152, 7785.766427007199, 7940.920590794133, 8171.550564718878, 8244.422890397735, 541.504523361128, 557.6458948567689, 562.1820251534008, 577.8353169346716, 602.4397310501275, 3779.640934111255, 3966.1076257842333, 3981.2634662587516, 4014.397840323916, 4053.2704113259306, 75032.87450688244, 92186.65300702672, 94605.58091628632, 94661.58007629891, 96620.0, 77767.0, 79646.60176699195, 82438.58780706058, 82961.0, 84223.0, 47870.56388308356, 58128.128078078866, 59398.40601594015, 65841.67079164478, 72487.46293879689, 7971.800704982422, 8098.676052957816, 8117.228863258218, 8117.715879944174, 8170.0, 40374.990625234605, 41071.38392924049, 41317.793411032755, 42261.78869105635, 42719.29122835176, 57444.0, 59478.0, 60040.39959600348, 60563.091553627615, 60605.69697151486, 9481.194098501705, 10809.297395669257, 10957.671269861918, 10959.232853700165, 11059.0, 11683.299002059903, 11833.82249266279, 11896.524139034342, 12040.698982525157, 12060.0, 11174.106071514097, 11192.756399094615, 11366.659000230007, 11513.712157196138, 11558.42207889619, 10515.369077855354, 11147.609833655782, 11152.21934464612, 11491.827622585835, 11554.826677599674, 10836.0, 11188.66434006981, 11207.607733729606, 11602.187846850977, 12197.634070977885, 9510.619575216913, 9576.616935322787, 9654.662086827208, 9802.460864652512, 9981.550830212804, 9590.08894155054, 9790.706278811647, 9794.461304628028, 9890.258230632588, 9912.504374781376], "value": 6746.860701885108, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 112.0, 116.0, 115.0, 119.0, 165.0, 126.0, 116.0, 117.0, 113.0], "value": 121.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 127.0, 129.0, 232.0, 107.0, 166.0, 160.0, 139.0, 175.0, 122.0], "value": 153.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 196.0, 220.0, 261.0, 274.0, 265.0, 194.0, 194.0, 197.0], "value": 219.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 82.0, 76.0, 173.0, 58.0, 59.0, 75.0, 89.0, 133.0, 56.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 149.0, 79.0, 82.0, 80.0, 121.0, 78.0, 82.0, 122.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 88.0, 85.0, 87.0, 89.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 147.0, 138.0, 95.0, 122.0, 148.0, 95.0, 95.0, 94.0, 144.0], "value": 120.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 90.0, 89.0, 91.0, 91.0, 89.0, 90.0, 91.0], "value": 90.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 56.0, 118.0, 58.0, 56.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 75.0, 95.0, 65.0, 89.0, 60.0, 59.0, 58.0, 59.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 104.0, 84.0, 85.0, 87.0, 86.0, 84.0, 90.0, 90.0, 105.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 146.0, 142.0, 137.0, 122.0, 129.0, 179.0, 233.0, 154.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 164.0, 168.0, 164.0, 164.0, 172.0, 193.0, 153.0, 158.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 77.0, 86.0, 85.0, 83.0, 77.0, 64.0, 65.0, 65.0, 67.0], "value": 73.1, "unit": "score"}]}]} 05:38:29 INFO - Return code: 0 05:38:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:38:29 ERROR - # TBPL SUCCESS # 05:38:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:29 INFO - 2016-05-22 05:38:29.690 screenresolution[1994:32031] starting screenresolution argv=screenresolution get 05:38:29 INFO - 2016-05-22 05:38:29.693 screenresolution[1994:32031] Display 0: 1600x1200x32@60 05:38:29 INFO - 2016-05-22 05:38:29.699 screenresolution[1995:32034] starting screenresolution argv=screenresolution list 05:38:29 INFO - Available Modes on Display 0 05:38:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:29 INFO - Intel Iris: 05:38:29 INFO - Chipset Model: Intel Iris 05:38:29 INFO - Type: GPU 05:38:29 INFO - Bus: Built-In 05:38:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:29 INFO - Vendor: Intel (0x8086) 05:38:29 INFO - Device ID: 0x0a2e 05:38:29 INFO - Revision ID: 0x0009 05:38:29 INFO - Displays: 05:38:29 INFO - Display: 05:38:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:29 INFO - Display Serial Number: 1600x1200 60 05:38:29 INFO - Main Display: Yes 05:38:29 INFO - Mirror: Off 05:38:29 INFO - Online: Yes 05:38:29 INFO - Rotation: Supported 05:38:29 INFO - Return code: 0 05:38:29 INFO - Running post-action listener: _resource_record_post_action 05:38:29 INFO - ##### 05:38:29 INFO - ##### Finished run-tests step (success) 05:38:29 INFO - ##### 05:38:29 INFO - Running post-run listener: _resource_record_post_run 05:38:29 INFO - Total resource usage - Wall time: 1207s; CPU: 55.0%; Read bytes: 1409024; Write bytes: 697444864; Read time: 11; Write time: 1928 05:38:29 INFO - TinderboxPrint: CPU usage
54.5% 05:38:29 INFO - TinderboxPrint: I/O read bytes / time
1,409,024 / 11 05:38:29 INFO - TinderboxPrint: I/O write bytes / time
697,444,864 / 1,928 05:38:29 INFO - TinderboxPrint: CPU idle
2,195.9 (45.5%) 05:38:29 INFO - TinderboxPrint: CPU system
973.6 (20.2%) 05:38:29 INFO - TinderboxPrint: CPU user
1,660.2 (34.4%) 05:38:29 INFO - TinderboxPrint: Swap in / out
397,459,456 / 0 05:38:29 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 385841664; Write bytes: 380159488; Read time: 28360; Write time: 755 05:38:30 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 1409024; Write bytes: 297772032; Read time: 11; Write time: 1118 05:38:30 INFO - Running post-run listener: _upload_blobber_files 05:38:30 INFO - Blob upload gear active. 05:38:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:38:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:38:30 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:38:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:38:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:32 INFO - (blobuploader) - INFO - Done attempting. 05:38:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:38:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:38:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:33 INFO - (blobuploader) - INFO - Done attempting. 05:38:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:38:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:35 INFO - (blobuploader) - INFO - Done attempting. 05:38:35 INFO - (blobuploader) - INFO - Iteration through files over. 05:38:35 INFO - Return code: 0 05:38:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:38:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:38:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d1c02e830dcbb85951d7fad91a7674311e935f5bc3713962fb17d87d6f2052476dbff8418192e0dfda81ab75f6e6e4de694e99a259c09abaa751cb47cf15e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23bd12be64ece2c92838b05b74747427271326f06a50b5959aad04a117093925ca9147f2e792f8ec5bff7273723897ebad29963bfca4f5a9ab1dccc9444cee7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e99dd3e584c56cc61a877075bac7176d3ca45dd42e85a1b6697368ee08ab0c2cc4251e1dbb43b8c0c2c3b513a60acc0c18bab8f6e7a1f3e377e0391058c1eb6"} 05:38:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:38:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:38:35 INFO - Contents: 05:38:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d1c02e830dcbb85951d7fad91a7674311e935f5bc3713962fb17d87d6f2052476dbff8418192e0dfda81ab75f6e6e4de694e99a259c09abaa751cb47cf15e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23bd12be64ece2c92838b05b74747427271326f06a50b5959aad04a117093925ca9147f2e792f8ec5bff7273723897ebad29963bfca4f5a9ab1dccc9444cee7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e99dd3e584c56cc61a877075bac7176d3ca45dd42e85a1b6697368ee08ab0c2cc4251e1dbb43b8c0c2c3b513a60acc0c18bab8f6e7a1f3e377e0391058c1eb6"} 05:38:35 INFO - Running post-run listener: copy_logs_to_upload_dir 05:38:35 INFO - Copying logs to upload dir... 05:38:35 INFO - mkdir: /builds/slave/test/build/upload/logs 05:38:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.383203 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-22 05:38:35.686528) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 05:38:35.690083) ========= 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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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/1d1c02e830dcbb85951d7fad91a7674311e935f5bc3713962fb17d87d6f2052476dbff8418192e0dfda81ab75f6e6e4de694e99a259c09abaa751cb47cf15e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23bd12be64ece2c92838b05b74747427271326f06a50b5959aad04a117093925ca9147f2e792f8ec5bff7273723897ebad29963bfca4f5a9ab1dccc9444cee7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e99dd3e584c56cc61a877075bac7176d3ca45dd42e85a1b6697368ee08ab0c2cc4251e1dbb43b8c0c2c3b513a60acc0c18bab8f6e7a1f3e377e0391058c1eb6"} build_url:https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011290 build_url: 'https://queue.taskcluster.net/v1/task/F1Bh8V_VSoCQJHd3bCpIYw/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/1d1c02e830dcbb85951d7fad91a7674311e935f5bc3713962fb17d87d6f2052476dbff8418192e0dfda81ab75f6e6e4de694e99a259c09abaa751cb47cf15e7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23bd12be64ece2c92838b05b74747427271326f06a50b5959aad04a117093925ca9147f2e792f8ec5bff7273723897ebad29963bfca4f5a9ab1dccc9444cee7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e99dd3e584c56cc61a877075bac7176d3ca45dd42e85a1b6697368ee08ab0c2cc4251e1dbb43b8c0c2c3b513a60acc0c18bab8f6e7a1f3e377e0391058c1eb6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 05:38:35.719328) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 05:38:35.719609) ========= 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.jU7aSAOmi9/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.8MZVw3TGXk/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.005214 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 05:38:35.767086) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 05:38:35.767349) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 05:38:35.767637) ========= ========= Total master_lag: 0.23 =========