builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0292 starttime: 1463488244.82 results: success (0) buildid: 20160515203829 builduid: fd462b370e5544bdae0208c7f82b5fc7 revision: d0be57e84807ce0853b2406de7ff6abb195ac898 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:44.815500) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:44.815935) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:44.816225) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:44.868602) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 05:30:44.868879) ========= 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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-17 05:30:44-- 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% 54.9M=0s 2016-05-17 05:30:44 (54.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145939 ========= master_lag: 1.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 05:30:46.293272) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:46.293660) ========= 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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.025108 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:46.384666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 05:30:46.385116) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0be57e84807ce0853b2406de7ff6abb195ac898 --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 d0be57e84807ce0853b2406de7ff6abb195ac898 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lL1GwUxlEX/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.WRLQlsJmyt/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-17 05:30:47,083 truncating revision to first 12 chars 2016-05-17 05:30:47,083 Setting DEBUG logging. 2016-05-17 05:30:47,083 attempt 1/10 2016-05-17 05:30:47,083 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0be57e84807?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 05:30:48,094 unpacking tar archive at: mozilla-central-d0be57e84807/testing/mozharness/ program finished with exit code 0 elapsedTime=1.259979 ========= master_lag: 0.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 05:30:48.315340) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:48.315673) ========= script_repo_revision: d0be57e84807ce0853b2406de7ff6abb195ac898 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:48.316112) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:48.316395) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:48.331026) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-17 05:30:48.331415) ========= /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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:30:48 INFO - MultiFileLogger online at 20160517 05:30:48 in /builds/slave/test 05:30:48 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:30:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:30:48 INFO - {'append_to_log': False, 05:30:48 INFO - 'base_work_dir': '/builds/slave/test', 05:30:48 INFO - 'blob_upload_branch': 'Firefox', 05:30:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:30:48 INFO - 'branch': 'Firefox', 05:30:48 INFO - 'buildbot_json_path': 'buildprops.json', 05:30:48 INFO - 'config_files': ('talos/mac_config.py',), 05:30:48 INFO - 'default_actions': ('clobber', 05:30:48 INFO - 'read-buildbot-config', 05:30:48 INFO - 'download-and-extract', 05:30:48 INFO - 'populate-webroot', 05:30:48 INFO - 'create-virtualenv', 05:30:48 INFO - 'install', 05:30:48 INFO - 'run-tests'), 05:30:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:30:48 INFO - 'download_minidump_stackwalk': True, 05:30:48 INFO - 'download_symbols': 'ondemand', 05:30:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:30:48 INFO - 'tooltool.py': '/tools/tooltool.py', 05:30:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:30:48 INFO - '/tools/misc-python/virtualenv.py')}, 05:30:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:30:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:30:48 INFO - 'installer_path': 'installer.exe', 05:30:48 INFO - 'log_level': 'info', 05:30:48 INFO - 'log_name': 'talos', 05:30:48 INFO - 'log_to_console': True, 05:30:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:30:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:30:48 INFO - 'opt_config_files': (), 05:30:48 INFO - 'pip_index': False, 05:30:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:48 INFO - 'cmd': ('bash', 05:30:48 INFO - '-c', 05:30:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:48 INFO - 'enabled': True, 05:30:48 INFO - 'halt_on_failure': False, 05:30:48 INFO - 'name': 'check_screen_resolution'},), 05:30:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:48 INFO - 'cmd': ('bash', 05:30:48 INFO - '-c', 05:30:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:48 INFO - 'enabled': True, 05:30:48 INFO - 'halt_on_failure': False, 05:30:48 INFO - 'name': 'check_screen_resolution'},), 05:30:48 INFO - 'run_cmd_checks_enabled': True, 05:30:48 INFO - 'sps_profile': False, 05:30:48 INFO - 'sps_profile_interval': 0, 05:30:48 INFO - 'suite': 'dromaeojs-e10s', 05:30:48 INFO - 'system_bits': '32', 05:30:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:30:48 INFO - 'title': 't-yosemite-r7-0292', 05:30:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:30:48 INFO - 'use_talos_json': True, 05:30:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:30:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:30:48 INFO - 'work_dir': 'build'} 05:30:48 INFO - ##### 05:30:48 INFO - ##### Running clobber step. 05:30:48 INFO - ##### 05:30:48 INFO - Running pre-action listener: _resource_record_pre_action 05:30:48 INFO - Running main action method: clobber 05:30:48 INFO - rmtree: /builds/slave/test/build 05:30:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:30:50 INFO - Running post-action listener: _resource_record_post_action 05:30:50 INFO - ##### 05:30:50 INFO - ##### Finished clobber step (success) 05:30:50 INFO - ##### 05:30:50 INFO - ##### 05:30:50 INFO - ##### Running read-buildbot-config step. 05:30:50 INFO - ##### 05:30:50 INFO - Running pre-action listener: _resource_record_pre_action 05:30:50 INFO - Running main action method: read_buildbot_config 05:30:50 INFO - Using buildbot properties: 05:30:50 INFO - { 05:30:50 INFO - "project": "", 05:30:50 INFO - "product": "firefox", 05:30:50 INFO - "script_repo_revision": "production", 05:30:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:30:50 INFO - "repository": "", 05:30:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:30:50 INFO - "buildid": "20160515203829", 05:30:50 INFO - "pgo_build": "False", 05:30:50 INFO - "basedir": "/builds/slave/test", 05:30:50 INFO - "buildnumber": 13, 05:30:50 INFO - "slavename": "t-yosemite-r7-0292", 05:30:50 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:30:50 INFO - "platform": "yosemite_r7", 05:30:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:30:50 INFO - "branch": "mozilla-central", 05:30:50 INFO - "repo_path": "mozilla-central", 05:30:50 INFO - "revision": "d0be57e84807ce0853b2406de7ff6abb195ac898", 05:30:50 INFO - "stage_platform": "macosx64", 05:30:50 INFO - "builduid": "fd462b370e5544bdae0208c7f82b5fc7", 05:30:50 INFO - "slavebuilddir": "test" 05:30:50 INFO - } 05:30:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:30:50 INFO - Running post-action listener: _resource_record_post_action 05:30:50 INFO - ##### 05:30:50 INFO - ##### Finished read-buildbot-config step (success) 05:30:50 INFO - ##### 05:30:50 INFO - ##### 05:30:50 INFO - ##### Running download-and-extract step. 05:30:50 INFO - ##### 05:30:50 INFO - Running pre-action listener: _resource_record_pre_action 05:30:50 INFO - Running main action method: download_and_extract 05:30:50 INFO - mkdir: /builds/slave/test/build/tests 05:30: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:30:50 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:30:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:30:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:30:52 INFO - Downloaded 1199 bytes. 05:30:52 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:52 INFO - Using the following test package requirements: 05:30:52 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:30:52 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:52 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:30:52 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:52 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:30:52 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:52 INFO - u'jsshell-mac.zip'], 05:30:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:52 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:30:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:30:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:52 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:30:52 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:52 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:30:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:52 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:30:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:52 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:30:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:52 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:30:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:30:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:30:54 INFO - Downloaded 18267670 bytes. 05:30:54 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:30:54 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:30:57 INFO - Return code: 0 05:30:57 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:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:57 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:30:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:30:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:30:58 INFO - Downloaded 18267670 bytes. 05:30:58 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:30:58 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:31:01 INFO - Return code: 0 05:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:01 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:31:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:31:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:31:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:31:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:31:02 INFO - Downloaded 10840194 bytes. 05:31:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:31:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:31:03 INFO - Return code: 0 05:31: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:31:03 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:31:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:31:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:31:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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:31:06 INFO - Downloaded 110069099 bytes. 05:31:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:31:06 INFO - mkdir: /builds/slave/test/properties 05:31:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:31:06 INFO - Writing to file /builds/slave/test/properties/build_url 05:31:06 INFO - Contents: 05:31:06 INFO - build_url:https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:31:08 INFO - Running post-action listener: _resource_record_post_action 05:31:08 INFO - Running post-action listener: set_extra_try_arguments 05:31:08 INFO - ##### 05:31:08 INFO - ##### Finished download-and-extract step (success) 05:31:08 INFO - ##### 05:31:08 INFO - ##### 05:31:08 INFO - ##### Running populate-webroot step. 05:31:08 INFO - ##### 05:31:08 INFO - Running pre-action listener: _resource_record_pre_action 05:31:08 INFO - Running main action method: populate_webroot 05:31:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:08 INFO - '--tppagecycles', 05:31:08 INFO - '7'], 05:31:08 INFO - 'tests': ['tsvgm']}}, 05:31:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:31:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:31:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:31:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:08 INFO - 'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:08 INFO - 'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['damp', 'tps']}, 05:31:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:08 INFO - 'tests': ['damp', 'tps']}, 05:31:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['dromaeo_dom']}, 05:31:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['a11yr', 05:31:08 INFO - 'ts_paint', 05:31:08 INFO - 'tpaint', 05:31:08 INFO - 'sessionrestore', 05:31:08 INFO - 'sessionrestore_no_auto_restore', 05:31:08 INFO - 'tabpaint']}, 05:31:08 INFO - 'other-e10s': {'tests': ['a11yr', 05:31:08 INFO - 'ts_paint', 05:31:08 INFO - 'tpaint', 05:31:08 INFO - 'sessionrestore', 05:31:08 INFO - 'sessionrestore_no_auto_restore', 05:31:08 INFO - 'tabpaint']}, 05:31:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:31:08 INFO - 'ts_paint', 05:31:08 INFO - 'tpaint', 05:31:08 INFO - 'sessionrestore', 05:31:08 INFO - 'sessionrestore_no_auto_restore', 05:31:08 INFO - 'tabpaint']}, 05:31:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:31:08 INFO - 'ts_paint', 05:31:08 INFO - 'tpaint', 05:31:08 INFO - 'sessionrestore', 05:31:08 INFO - 'sessionrestore_no_auto_restore', 05:31:08 INFO - 'tabpaint']}, 05:31:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['a11yr', 05:31:08 INFO - 'ts_paint', 05:31:08 INFO - 'tpaint', 05:31:08 INFO - 'sessionrestore', 05:31:08 INFO - 'sessionrestore_no_auto_restore', 05:31:08 INFO - 'tabpaint']}, 05:31:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['a11yr', 05:31:08 INFO - 'ts_paint', 05:31:08 INFO - 'tpaint', 05:31:08 INFO - 'sessionrestore', 05:31:08 INFO - 'sessionrestore_no_auto_restore', 05:31:08 INFO - 'tabpaint']}, 05:31:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['tsvgx', 05:31:08 INFO - 'tsvgr_opacity', 05:31:08 INFO - 'tart', 05:31:08 INFO - 'tscrollx', 05:31:08 INFO - 'cart']}, 05:31:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:31:08 INFO - 'tsvgr_opacity', 05:31:08 INFO - 'tart', 05:31:08 INFO - 'tscrollx', 05:31:08 INFO - 'cart']}, 05:31:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:08 INFO - 'talos_options': ['--disable-e10s'], 05:31:08 INFO - 'tests': ['tp5o']}, 05:31:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:08 INFO - 'tests': ['tp5o']}, 05:31:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:08 INFO - 'talos_options': ['--disable-e10s', 05:31:08 INFO - '--xperf_path', 05:31:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:08 INFO - 'tests': ['tp5n']}, 05:31:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:08 INFO - 'talos_options': ['--xperf_path', 05:31:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:08 INFO - 'tests': ['tp5n']}}, 05:31:08 INFO - 'talos.zip': {'path': '', 05:31:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:08 INFO - Running post-action listener: _resource_record_post_action 05:31:08 INFO - ##### 05:31:08 INFO - ##### Finished populate-webroot step (success) 05:31:08 INFO - ##### 05:31:08 INFO - ##### 05:31:08 INFO - ##### Running create-virtualenv step. 05:31:08 INFO - ##### 05:31:08 INFO - Running pre-action listener: _resource_record_pre_action 05:31:08 INFO - Running main action method: create_virtualenv 05:31:08 INFO - Creating virtualenv /builds/slave/test/build/venv 05:31:08 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:31:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:31:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:08 INFO - Using real prefix '/tools/python27' 05:31:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:31:08 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:11 INFO - Installing pip.................done. 05:31:11 INFO - Return code: 0 05:31:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:11 INFO - 'HOME': '/Users/cltbld', 05:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:11 INFO - 'LOGNAME': 'cltbld', 05:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:11 INFO - 'MOZ_NO_REMOTE': '1', 05:31:11 INFO - 'NO_EM_RESTART': '1', 05:31:11 INFO - 'PAGER': '/bin/cat', 05:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:11 INFO - 'PWD': '/builds/slave/test', 05:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:11 INFO - 'SHELL': '/bin/bash', 05:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:11 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:11 INFO - 'USER': 'cltbld', 05:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:11 INFO - 'XPC_FLAGS': '0x0', 05:31:11 INFO - 'XPC_SERVICE_NAME': '0', 05:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:12 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:31:12 INFO - Cleaning up... 05:31:12 INFO - Return code: 0 05:31:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:31: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:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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:31:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:31:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:12 INFO - 'HOME': '/Users/cltbld', 05:31:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:12 INFO - 'LOGNAME': 'cltbld', 05:31:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:12 INFO - 'MOZ_NO_REMOTE': '1', 05:31:12 INFO - 'NO_EM_RESTART': '1', 05:31:12 INFO - 'PAGER': '/bin/cat', 05:31:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:12 INFO - 'PWD': '/builds/slave/test', 05:31:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:12 INFO - 'SHELL': '/bin/bash', 05:31:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:12 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:12 INFO - 'USER': 'cltbld', 05:31:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:12 INFO - 'XPC_FLAGS': '0x0', 05:31:12 INFO - 'XPC_SERVICE_NAME': '0', 05:31:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:12 INFO - Downloading/unpacking psutil>=3.1.1 05:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:15 INFO - Installing collected packages: psutil 05:31:15 INFO - Running setup.py install for psutil 05:31:15 INFO - building 'psutil._psutil_osx' extension 05:31:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:31:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:31:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:31:16 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:31:16 INFO - building 'psutil._psutil_posix' extension 05:31:16 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:31:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:31:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:31:16 INFO - ^ 05:31:16 INFO - 1 warning generated. 05:31:16 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:31:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:16 INFO - Successfully installed psutil 05:31:16 INFO - Cleaning up... 05:31:16 INFO - Return code: 0 05:31:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:31: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:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:16 INFO - 'HOME': '/Users/cltbld', 05:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:16 INFO - 'LOGNAME': 'cltbld', 05:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:16 INFO - 'MOZ_NO_REMOTE': '1', 05:31:16 INFO - 'NO_EM_RESTART': '1', 05:31:16 INFO - 'PAGER': '/bin/cat', 05:31:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:16 INFO - 'PWD': '/builds/slave/test', 05:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:16 INFO - 'SHELL': '/bin/bash', 05:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:16 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:16 INFO - 'USER': 'cltbld', 05:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:16 INFO - 'XPC_FLAGS': '0x0', 05:31:16 INFO - 'XPC_SERVICE_NAME': '0', 05:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:31:19 INFO - Installing collected packages: mozsystemmonitor 05:31:19 INFO - Running setup.py install for mozsystemmonitor 05:31:19 INFO - Successfully installed mozsystemmonitor 05:31:19 INFO - Cleaning up... 05:31:19 INFO - Return code: 0 05:31:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:19 INFO - 'HOME': '/Users/cltbld', 05:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:19 INFO - 'LOGNAME': 'cltbld', 05:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:19 INFO - 'MOZ_NO_REMOTE': '1', 05:31:19 INFO - 'NO_EM_RESTART': '1', 05:31:19 INFO - 'PAGER': '/bin/cat', 05:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:19 INFO - 'PWD': '/builds/slave/test', 05:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:19 INFO - 'SHELL': '/bin/bash', 05:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:19 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:19 INFO - 'USER': 'cltbld', 05:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:19 INFO - 'XPC_FLAGS': '0x0', 05:31:19 INFO - 'XPC_SERVICE_NAME': '0', 05:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:19 INFO - Downloading/unpacking blobuploader==1.2.4 05:31: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:31: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:31: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:31: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:31: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:31: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:31:22 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:31:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:23 INFO - Downloading docopt-0.6.1.tar.gz 05:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:23 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:23 INFO - Running setup.py install for blobuploader 05:31:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:31:23 INFO - Running setup.py install for requests 05:31:24 INFO - Running setup.py install for docopt 05:31:24 INFO - Successfully installed blobuploader requests docopt 05:31:24 INFO - Cleaning up... 05:31:24 INFO - Return code: 0 05:31:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:24 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 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:24 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:31:24 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:31:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:24 INFO - 'HOME': '/Users/cltbld', 05:31:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:24 INFO - 'LOGNAME': 'cltbld', 05:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:24 INFO - 'MOZ_NO_REMOTE': '1', 05:31:24 INFO - 'NO_EM_RESTART': '1', 05:31:24 INFO - 'PAGER': '/bin/cat', 05:31:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:24 INFO - 'PWD': '/builds/slave/test', 05:31:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:24 INFO - 'SHELL': '/bin/bash', 05:31:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:24 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:24 INFO - 'USER': 'cltbld', 05:31:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:24 INFO - 'XPC_FLAGS': '0x0', 05:31:24 INFO - 'XPC_SERVICE_NAME': '0', 05:31:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:24 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-66C0C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:24 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Dy_w76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:24 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-WyJ4jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:24 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-rT15cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:24 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_Jp9vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-LCcjik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gIPoKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gzvq1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-OckR8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ROq2X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-7A8A3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-SuoLc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-DT5cAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-iWoN8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:25 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-WBXYzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:26 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-jyiQOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:26 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-jmQyWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:26 INFO - Running setup.py install for manifestparser 05:31:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:31:26 INFO - Running setup.py install for mozcrash 05:31:26 INFO - Running setup.py install for mozdebug 05:31:26 INFO - Running setup.py install for mozdevice 05:31:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:31:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:31:26 INFO - Running setup.py install for mozfile 05:31:26 INFO - Running setup.py install for mozhttpd 05:31:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:31:27 INFO - Running setup.py install for mozinfo 05:31:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:31:27 INFO - Running setup.py install for mozInstall 05:31:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:31:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:31:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:31:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:31:27 INFO - Running setup.py install for mozleak 05:31:27 INFO - Running setup.py install for mozlog 05:31:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:31:27 INFO - Running setup.py install for moznetwork 05:31:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:31:27 INFO - Running setup.py install for mozprocess 05:31:28 INFO - Running setup.py install for mozprofile 05:31:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:31:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:31:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:31:28 INFO - Running setup.py install for mozrunner 05:31:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:31:28 INFO - Running setup.py install for mozscreenshot 05:31:28 INFO - Running setup.py install for moztest 05:31:28 INFO - Running setup.py install for mozversion 05:31:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:31:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:28 INFO - Cleaning up... 05:31:28 INFO - Return code: 0 05:31:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:31:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:28 INFO - 'HOME': '/Users/cltbld', 05:31:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:28 INFO - 'LOGNAME': 'cltbld', 05:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:28 INFO - 'MOZ_NO_REMOTE': '1', 05:31:28 INFO - 'NO_EM_RESTART': '1', 05:31:28 INFO - 'PAGER': '/bin/cat', 05:31:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:28 INFO - 'PWD': '/builds/slave/test', 05:31:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:28 INFO - 'SHELL': '/bin/bash', 05:31:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:28 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:28 INFO - 'USER': 'cltbld', 05:31:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:28 INFO - 'XPC_FLAGS': '0x0', 05:31:28 INFO - 'XPC_SERVICE_NAME': '0', 05:31:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-os3zE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:29 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:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-OjYBuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-NX9BCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-1qpnil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-noMA3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-fZskOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-DIk_jN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-k0SsxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-SLZVb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:30 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:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:30 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-isofea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:30 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:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:30 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-55OvWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:30 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-owskJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:30 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-fjVSpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:30 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-EiUv4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:30 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-cuC5QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:30 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-8O4oUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:30 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-hm18uR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:31:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:33 INFO - Downloading blessings-1.6.tar.gz 05:31:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:33 INFO - Installing collected packages: blessings 05:31:33 INFO - Running setup.py install for blessings 05:31:33 INFO - Successfully installed blessings 05:31:33 INFO - Cleaning up... 05:31:33 INFO - Return code: 0 05:31:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:31:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:33 INFO - Reading from file tmpfile_stdout 05:31:33 INFO - Current package versions: 05:31:33 INFO - blessings == 1.6 05:31:33 INFO - blobuploader == 1.2.4 05:31:33 INFO - docopt == 0.6.1 05:31:33 INFO - manifestparser == 1.1 05:31:33 INFO - mozInstall == 1.12 05:31:33 INFO - mozcrash == 0.17 05:31:33 INFO - mozdebug == 0.1 05:31:33 INFO - mozdevice == 0.48 05:31:33 INFO - mozfile == 1.2 05:31:33 INFO - mozhttpd == 0.7 05:31:33 INFO - mozinfo == 0.9 05:31:33 INFO - mozleak == 0.1 05:31:33 INFO - mozlog == 3.2 05:31:33 INFO - moznetwork == 0.27 05:31:33 INFO - mozprocess == 0.22 05:31:33 INFO - mozprofile == 0.28 05:31:33 INFO - mozrunner == 6.11 05:31:33 INFO - mozscreenshot == 0.1 05:31:33 INFO - mozsystemmonitor == 0.3 05:31:33 INFO - moztest == 0.7 05:31:33 INFO - mozversion == 1.4 05:31:33 INFO - psutil == 3.1.1 05:31:33 INFO - requests == 1.2.3 05:31:33 INFO - wsgiref == 0.1.2 05:31:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:34 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 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:34 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:31:34 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:31:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:34 INFO - 'HOME': '/Users/cltbld', 05:31:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:34 INFO - 'LOGNAME': 'cltbld', 05:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:34 INFO - 'MOZ_NO_REMOTE': '1', 05:31:34 INFO - 'NO_EM_RESTART': '1', 05:31:34 INFO - 'PAGER': '/bin/cat', 05:31:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:34 INFO - 'PWD': '/builds/slave/test', 05:31:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:34 INFO - 'SHELL': '/bin/bash', 05:31:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:34 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:34 INFO - 'USER': 'cltbld', 05:31:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:34 INFO - 'XPC_FLAGS': '0x0', 05:31:34 INFO - 'XPC_SERVICE_NAME': '0', 05:31:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:31:34 INFO - Cleaning up... 05:31:34 INFO - Return code: 0 05:31:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:31:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:34 INFO - 'HOME': '/Users/cltbld', 05:31:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:34 INFO - 'LOGNAME': 'cltbld', 05:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:34 INFO - 'MOZ_NO_REMOTE': '1', 05:31:34 INFO - 'NO_EM_RESTART': '1', 05:31:34 INFO - 'PAGER': '/bin/cat', 05:31:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:34 INFO - 'PWD': '/builds/slave/test', 05:31:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:34 INFO - 'SHELL': '/bin/bash', 05:31:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:34 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:34 INFO - 'USER': 'cltbld', 05:31:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:34 INFO - 'XPC_FLAGS': '0x0', 05:31:34 INFO - 'XPC_SERVICE_NAME': '0', 05:31:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:35 INFO - Downloading/unpacking jsonschema 05:31:35 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:31:35 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:31:35 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:31:35 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:31:35 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:31:35 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:31:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:37 INFO - Downloading/unpacking functools32 (from jsonschema) 05:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:38 INFO - Downloading functools32-3.2.3-2.tar.gz 05:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:38 INFO - warning: no files found matching '*.txt' 05:31:38 INFO - no previously-included directories found matching 'build' 05:31:38 INFO - no previously-included directories found matching 'dist' 05:31:38 INFO - no previously-included directories found matching '.git*' 05:31:38 INFO - Installing collected packages: jsonschema, functools32 05:31:38 INFO - Running setup.py install for functools32 05:31:38 INFO - warning: no files found matching '*.txt' 05:31:38 INFO - no previously-included directories found matching 'build' 05:31:38 INFO - no previously-included directories found matching 'dist' 05:31:38 INFO - no previously-included directories found matching '.git*' 05:31:38 INFO - Successfully installed jsonschema functools32 05:31:38 INFO - Cleaning up... 05:31:38 INFO - Return code: 0 05:31:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:31:38 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:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:38 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:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10c731a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c737570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d94bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, '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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:31:38 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:31:38 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:31:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:31:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:38 INFO - 'HOME': '/Users/cltbld', 05:31:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:38 INFO - 'LOGNAME': 'cltbld', 05:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:38 INFO - 'MOZ_NO_REMOTE': '1', 05:31:38 INFO - 'NO_EM_RESTART': '1', 05:31:38 INFO - 'PAGER': '/bin/cat', 05:31:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:38 INFO - 'PWD': '/builds/slave/test', 05:31:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:38 INFO - 'SHELL': '/bin/bash', 05:31:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:31:38 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:31:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:38 INFO - 'USER': 'cltbld', 05:31:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:38 INFO - 'XPC_FLAGS': '0x0', 05:31:38 INFO - 'XPC_SERVICE_NAME': '0', 05:31:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:38 INFO - Downloading/unpacking flake8 05:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:38 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:31:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:31:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:31:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:31:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:31:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:31:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:31:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:31:42 INFO - no previously-included directories found matching 'docs/_build' 05:31:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:31:42 INFO - Running setup.py install for pep8 05:31:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:31:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:31:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:31:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:31:42 INFO - no previously-included directories found matching 'docs/_build' 05:31:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:31:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:31:42 INFO - Cleaning up... 05:31:43 INFO - Return code: 0 05:31:43 INFO - Running post-action listener: _resource_record_post_action 05:31:43 INFO - Running post-action listener: _start_resource_monitoring 05:31:43 INFO - Starting resource monitoring. 05:31:43 INFO - ##### 05:31:43 INFO - ##### Finished create-virtualenv step (success) 05:31:43 INFO - ##### 05:31:43 INFO - ##### 05:31:43 INFO - ##### Running install step. 05:31:43 INFO - ##### 05:31:43 INFO - Running pre-action listener: _resource_record_pre_action 05:31:43 INFO - Running main action method: install 05:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:43 INFO - Reading from file tmpfile_stdout 05:31:43 INFO - Detecting whether we're running mozinstall >=1.0... 05:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:31:43 INFO - Reading from file tmpfile_stdout 05:31:43 INFO - Output received: 05:31:43 INFO - Usage: mozinstall [options] installer 05:31:43 INFO - Options: 05:31:43 INFO - -h, --help show this help message and exit 05:31:43 INFO - -d DEST, --destination=DEST 05:31:43 INFO - Directory to install application into. [default: 05:31:43 INFO - "/builds/slave/test"] 05:31:43 INFO - --app=APP Application being installed. [default: firefox] 05:31:43 INFO - mkdir: /builds/slave/test/build/application 05:31:43 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:31:43 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:32:16 INFO - Reading from file tmpfile_stdout 05:32:16 INFO - Output received: 05:32:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:32:16 INFO - Running post-action listener: _resource_record_post_action 05:32:16 INFO - ##### 05:32:16 INFO - ##### Finished install step (success) 05:32:16 INFO - ##### 05:32:16 INFO - ##### 05:32:16 INFO - ##### Running run-tests step. 05:32:16 INFO - ##### 05:32:16 INFO - Running pre-action listener: _resource_record_pre_action 05:32:16 INFO - Running main action method: run_tests 05:32:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:32:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:32:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:32:16 INFO - 2016-05-17 05:32:16.639 screenresolution[1639:7540] starting screenresolution argv=screenresolution get 05:32:16 INFO - 2016-05-17 05:32:16.642 screenresolution[1639:7540] Display 0: 1600x1200x32@60 05:32:16 INFO - 2016-05-17 05:32:16.648 screenresolution[1640:7542] starting screenresolution argv=screenresolution list 05:32:16 INFO - Available Modes on Display 0 05:32:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:32:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:32:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:32:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:32:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:32:16 INFO - Intel Iris: 05:32:16 INFO - Chipset Model: Intel Iris 05:32:16 INFO - Type: GPU 05:32:16 INFO - Bus: Built-In 05:32:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:32:16 INFO - Vendor: Intel (0x8086) 05:32:16 INFO - Device ID: 0x0a2e 05:32:16 INFO - Revision ID: 0x0009 05:32:16 INFO - Displays: 05:32:16 INFO - Display: 05:32:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:32:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:32:16 INFO - Display Serial Number: 1600x1200 60 05:32:16 INFO - Main Display: Yes 05:32:16 INFO - Mirror: Off 05:32:16 INFO - Online: Yes 05:32:16 INFO - Rotation: Supported 05:32:16 INFO - Return code: 0 05:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:32:16 INFO - Python 2.7.3 05:32:16 INFO - Return code: 0 05:32:16 INFO - grabbing minidump binary from tooltool 05:32: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:32:16 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 0x10c5d5d30>, '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 0x10c3f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ccb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:32:16 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:32:16 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:32:16 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:32:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:17 INFO - Return code: 0 05:32:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:32:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:32:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:32:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:32:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:32:18 INFO - Return code: 0 05:32:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --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:32:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lL1GwUxlEX/Render', 05:32:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:18 INFO - 'HOME': '/Users/cltbld', 05:32:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:18 INFO - 'LOGNAME': 'cltbld', 05:32:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:32:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:18 INFO - 'MOZ_NO_REMOTE': '1', 05:32:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:32:18 INFO - 'NO_EM_RESTART': '1', 05:32:18 INFO - 'PAGER': '/bin/cat', 05:32:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:18 INFO - 'PWD': '/builds/slave/test', 05:32:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:32:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:18 INFO - 'SHELL': '/bin/bash', 05:32:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WRLQlsJmyt/Listeners', 05:32:18 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 05:32:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:18 INFO - 'USER': 'cltbld', 05:32:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:18 INFO - 'XPC_FLAGS': '0x0', 05:32:18 INFO - 'XPC_SERVICE_NAME': '0', 05:32:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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:32:18 INFO - mozversion application_buildid: 20160515203829 05:32:18 INFO - mozversion application_changeset: d0be57e84807ce0853b2406de7ff6abb195ac898 05:32:18 INFO - mozversion application_display_name: Nightly 05:32:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:18 INFO - mozversion application_name: Firefox 05:32:18 INFO - mozversion application_remotingname: firefox 05:32:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:18 INFO - mozversion application_vendor: Mozilla 05:32:18 INFO - mozversion application_version: 49.0a1 05:32:18 INFO - mozversion platform_buildid: 20160515203829 05:32:18 INFO - mozversion platform_changeset: d0be57e84807ce0853b2406de7ff6abb195ac898 05:32:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:18 INFO - mozversion platform_version: 49.0a1 05:32:18 INFO - using testdate: 1463488338 05:32:18 INFO - actual date: 1463488338 05:32:18 INFO - starting webserver on 'localhost:49271' 05:32:18 INFO - SUITE-START | Running 2 tests 05:32:18 INFO - TEST-START | dromaeo_css 05:32:18 INFO - operating with platform_type : mac_ 05:32:18 INFO - Initialising browser for dromaeo_css test... 05:32:18 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpafb5ED/profile http://localhost:49271/getInfo.html) 05:32:21 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 05:32:21 INFO - PROCESS | 1658 | colorDepth:24 05:32:21 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 05:32:21 INFO - PROCESS | 1658 | __metrics 05:32:21 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:32:21 INFO - TEST-INFO | 1658: exit 0 05:32:21 INFO - Browser initialized. 05:32:21 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:21 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpafb5ED/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:33 INFO - PROCESS | 1661 | RSS: Main: 249921536 05:32:33 INFO - PROCESS | 1661 | 05:33:23 INFO - PROCESS | 1661 | 1463488403467 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:35:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:35:35 INFO - PROCESS | 1661 | RSS: Main: 262303744 05:35:35 INFO - PROCESS | 1661 | 05:38:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:38:38 INFO - PROCESS | 1661 | RSS: Main: 273813504 05:38:38 INFO - PROCESS | 1661 | 05:41:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:41:41 INFO - PROCESS | 1661 | RSS: Main: 275615744 05:41:41 INFO - PROCESS | 1661 | 05:44:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:44:43 INFO - PROCESS | 1661 | RSS: Main: 277499904 05:44:43 INFO - PROCESS | 1661 | 05:47:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:47:46 INFO - PROCESS | 1661 | RSS: Main: 280330240 05:47:46 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:50:49 INFO - PROCESS | 1661 | RSS: Main: 281133056 05:50:49 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | __start_tp_report 05:50:49 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 05:50:49 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 05:50:49 INFO - PROCESS | 1661 | |i|pagename|runs| 05:50:49 INFO - PROCESS | 1661 | |0;dojo.html;2910.8690108945098;2949.5723120147572;3473.7221022318217;3528.206153615437;3558.5195998540207;4836.782344794484;5575.358833734122;5866.120081987704;5896.705164741762;5927.525797936166;4488.461384633841;4932.704037757737;5861.970690146554;5922.466977971982;5954.106883967396;5127.564157046656;5179.948200517993;5258.973705131469;5352.57179425645;5407.324084489439;261.9817124745141;4957.398828113952;5260.289860868772;5353.330833645794;5415.891682166374;5323.22813192085;5330.200469929503;5349.251104845324;5354.946450535484;5389.434109418514;5338.679679219259;5360.865978350534;5396.811111611075;5401.405845357008;5410.729463526808;5191.039657663307;5227.895442091175;5296.311479507678;5301.1783173608155;5337.279467271907;5761.308642962871;5829.242198514208;5855.707214639251;5880.441358070976;5902;6167.814965551041;6172.47533959614;6236;6252.562320637558;6256.249250090018;6181.196444462236;6230.906536401958;6237.812865614039;6307.779227727054;6309.242890853081;6026.608270462406;6051.273847138327;6062.272527296708;6063.666498342589;6067.696535518577;5605.80379686709;6200.100985357189;6351.460125889259;6390.265119511223;6479.384458476437;6146.5390095743;6200.81397558074;6205.875882482415;6217.502599792006;6234.376562343729;6282.560220784501;6283.811485655347;6319.52603554726;6335.11308416822;6342.429181373606;6106.703363853616;6120.049813200498;6129.083728908246;6200.875982480326;6253.812385628438;6123.953766440866;6155;6168.228971378578;6168.8766224675255;6171.13604095427;4952.5542701157065;5010.924836127461;5023.598112151019;5025.3718285214345;5025.7989680412375;4398.758068306274;4488.753118578445;4508.842190523316;4546.568076032771;4589.2198326284615;5914.112883067488;5963.135345374896;6009.669468179205;6015.729292181863;6055.636661800306;6315.179026726496;6326.051092336186;6349.365063493706;6364.204474440695;6385.553011289165;5889.146073819272;5984.910226346608;6003.729832157476;6032.155498230251;6051.546134039964;5430.456954304617;5457.181422786613;5462.918056229159;5482.5613950883835;5513.09034009392;6340;6460.483161347081;6474.643894585843;6483.805485835433;6504.089427480157;6247.00047992319;6260;6301.684915754286;6384.265809431975;6386.425221730066;5387.595930305321;6523.054157147282;6529.379708927644;6566.409023187745;6570.211574611078;6107.765388666889;6132.04958718918;6242.781502647385;6256.937430625636;6265.718042688181;6187.969060154851;6276.905846412216;6329.1139240505445;6337.2395312562785;6352.047192921099;4702.365180700562;6183.258009038944;6190.380961903774;6198.969005154945;6212.409821066992;6124.47247923558;6143.143438301211;6161.353057928853;6186.721597528032;6198.194234749454;6067.575269731076;6150.231221097363;6156.907646385218;6185.659788711664;6192.380761923771;6159.538034647509;6170.475509581556;6185.752569897154;6189.535784816067;6192.195014648067;6057.273127224761;6105.308121371189;6114.266954506276;6132.202813634199;6183.628982261079 05:50:49 INFO - PROCESS | 1661 | |1;ext.html;503.3632454944495;507.2011581758731;534.1693666348829;578.8350320158755;600.3066458240734;40276.19447611046;42848.785756071215;42901.35647965283;42928.785356073175;43070;43735.78132109343;43928;44030.11939761203;44326;44362.66911992645;9571.425714457155;9746.220302375792;9787.070228328334;9811.607535698564;9826.852597211011;8104.1085480597085;8961.731148065535;9324.300677449184;9669.468179250138;9799.216062714966;8261.628226729781;9747.268954828382;9759.316847820655;9801.509924503782;9812.803743925117;9722.076402741764;9728.17310528606;9767.609295628166;9773.462459564686;9782.266330025277;8736.737897863075;9581.281403894736;9668.75828104296;9676.56455459506;9725.0761177688;32747.50878736821;39524.60475395238;40644;40649.187016259806;40787.79606101951;2771.6951135375093;2967.4065186962694;3082.9845850770603;3207.134073800084;3226.1450715560395;27251.046213382633;27522.587161192598;29291;29552.408951821057;29946.700532994608;2425.847722331896;2564.9487010259877;2638.5514462541414;2662.294491959632;2862.8854845806145;539150;544932;546406;548084.2595787036;548922;34850.651493484744;67589.66205168943;68006.97989530161;68117.65941170361;68147.65926170337;36328.27343453116;36521.63478365236;36630.81684591613;36735.08162295911;36799.4480082799;15575.454859079879;15660.216989150496;15689;15816.28826702801;15860.04839709621;12958.676033099247;12983.221006739626;13004.21974681522;13051.738965220642;13079.41142648583;8189.262966333059;8311.672731635152;8324.125966773401;8324.500529968222;8387.454815436977;2967.2581854536365;3419.316136772655;3473.114355839245;3491.2319289756215;3505.386557352453;988.5304480371766;990.8365119755159;1031.9277650564388;1061.0662616897234;1063.3672964586058;25087.62368564473;31164.065078047694;31491.527627085612;31661.52507712386;32440;1116.8771435142128;1134.3080720760329;1149.83327417524;1169.8128299472214;1256.9057320700983;14950.850491495163;15534.300956456986;15789.842101579066;15803.525894223192;15829.97105188159;575905;688896;695020;704013;710702;62112.378876211566;62264.688676556325;62485.375146247956;62570.37429625737;62672.68663656653;1265.2155663488695;1462.9195394253206;1494.5441640299596;1519.3770554072778;1524.0931645670807;29428.264293392836;29529;29593.40813183681;30037.54943675803;30287.545686814276;51705;52462.47537524728;52640;53320.2001969963;53468.73265633647;622630;623630;627782;628599;629500;22748.20381286647;25088.874555627106;25212.621810673223;25688.357791055372;26469.602955956063;47591;48294.27558586694;48463.5153648459;49076.75461622669;49319;31977.520337194495;39303.80348098241;39659.80170099131;40113.799431002655;40246.1950760989;37476.06309842276;38091.42862857004;38415.03962400962;38894.41658375183;39202.411963821134;38113;38383.80808095942;39205.41191882181;39333;39442.60557394504;25068.62397064082;33833.66166338404;34312.62749489993;34334.313313734085;35292.0589367577;20261.392158235278;25284;25610.359741006625;25655.358616034002;25799.484010320062 05:50:49 INFO - PROCESS | 1661 | |2;jquery.html;390.906182516196;396.93450580654195;397.167933179988;398.61931855078404;418.5416968419581;14685.706285874276;19555.826650400984;20421.48946276344;20751.858647774385;20781.688274675893;20172.59654806903;20669.483262918435;21152.682709759327;21157.68263476049;21171.682424763603;5333.4399888011785;5931.199288096116;6118.69406529674;6127.846803829895;6132.908006379907;5829.329627092875;6028.4272994065495;6041.365656606059;6077.939220607804;6117;5991.221141251631;6054.334023257438;6057.363976782441;6073.878522429549;6082.5742198046155;5511;5898.262717160355;5932.11018347247;6045.455908968192;6055.091736239578;4225.556316586745;5710.600257981943;5749.31008279005;5764.827055188351;5778.971105144489;25408.618870716953;25844.48311033769;26366.73633263681;26403.867980659972;26405.207843764718;2016;2059.3718915730697;2088.718023066882;2175.3039027511204;2463.458039231384;8824.911750882537;9271.953640231823;9709.61161553537;9830.361026533252;10208.132308753764;1787.731840223964;1789.4810504953548;1792.9103544822838;1823.124900047971;1826.3060037185785;114584;115859.4207028968;120588.79411205863;130214;130521.34739326338;11702.356370399539;11761.705957350963;11908;11971.281723096468;12062.311276421307;15192.316345764468;15309.387624494895;15361.6159596011;15410.922945385426;15480.380784768708;8855.697518645198;8913.420627659181;8926.375153739176;8943.642254309754;9047.728568142966;7461.291177338144;8828.558572071372;8845.42504737203;8918.375713700108;8926.553672316359;4710.152172608895;4720.976395118002;4747.667663263538;4781.091592597465;4805.255185446262;1891.990540047291;2043.6117137743781;2049.2069439240663;2065.2874758208395;2077.542940553076;815.8903890708632;851.1913682002123;890.4746199742183;912.379581884325;962.5908988679784;20233.595328093357;20569.8686572237;20674.58650827005;20848.895755521124;20918.79081209199;795.5783434779576;871.6252011634931;890.9910900891037;911.8541033434751;920.6593560382702;9102.499362535124;10714.678559643224;10793.730156746144;10857.728556785985;11086.667399978014;563946;598906;605211;619632;620289;131322.3735525284;134706.32646836704;135877.32061339827;135990;140656.89014664787;1013.7566983923953;1044.8171569975225;1051.8317069269033;1052.7999680060916;1056.0970370333407;17763.46709598714;18762.437126886216;19077.332293369665;19288.710669340253;19297.035148242252;47286;51530.22704659508;53527;55956;57140;77242.22757772575;77418;81690;86144;86675;11639.6508104757;11788.528458861549;11917.880821192024;11971.820422693492;12113.878861211628;12991;13198.868011320148;13329.533466328632;13399.397027133607;13481.528146514825;11262.099032077578;11270;11406.315621062762;11538.826917596074;11573.537058517564;11176.664700059011;11177;11204.775904482029;11248.718782030515;11393.715157120807;9346.392484488486;11636.592719254786;11780.469878855301;11853.5851245206;11921.701957451132;10717.678469645924;11965.581204657796;12160.695982600506;12178;12181.817272740738;9257;10983.890161098287;11097.778044439228;11404.372759498307;11408.657740267805 05:50:49 INFO - PROCESS | 1661 | |3;mootools.html;1618.4901755946876;1653.156889986107;1772.5568607848038;1912.665283575374;1929.3633101076646;8029.9197008029905;8623.870641940375;8672.176143266388;9153.954230228848;9208.723738287845;8700.43447175933;8770.254528365096;8868.556572171397;8923.732288031353;8924.59839307231;2432.562138815017;2466.839655422392;2467.247489515697;2467.4078221226864;2468.6420469031978;1849.953751156225;2268.820213488985;2415.1909110448023;2456.103522214388;2488.464980029293;2450.3996520852415;2456.005317846275;2458.7418321076298;2469.1975108089855;2490.663760392342;2416.6375043743406;2432.6837511123617;2437.2688193541953;2452.681151450318;2462.5321188974217;1951.882887026783;2120.801747012948;2356.4344557306295;2369.834111612188;2371.513839662865;7404.1855395906405;8881.378303518757;8978.281737460988;8982.550872456351;8985.011648718635;5357.544608708265;5481.506664400223;5484.095124304525;5520.641158324696;5538.529225015878;3512.367773800715;3555.3600351936643;3568.80371579563;3629.2922880038404;3633.5458067741533;997.2072202598952;1001.2190491416634;1006.8842083160457;1021.667957913681;1035.8653375061194;11637.47631356591;11742.060635149168;11750.882491175149;11756.706082347924;11783.410829458493;4808.735519546389;4836.637252206098;4921.655484116149;4941.901161976741;4942.332785073969;10140.695779126638;10144.492775361203;10175.9491202545;10185.94907025475;10192.337498062601;5435.83692489226;5469.890602187934;5514.476124768141;5546.639468434539;5572.08060669993;4492.191405547033;4851.417829860368;5051.82318618854;5054.9241761373605;5060.822871199491;4110.506739191256;4134.545200027995;4166.7708276045105;4170.395292682544;4206.095689426788;1351.1892864281353;1358.6127953533196;1374.9518766843112;1381.0470775165104;1387.257817067864;861.0356400831109;882.7351794461636;901.7655409593422;932.4917919733762;943.8725772020689;5564.33228012641;5853.151293062482;5972.313183983811;5985.221921150222;6163.414475624809;925.1627277313908;941.6751220828803;969.1471505075489;982.2289502740315;1016.3444578246989;3929.901752456154;3956.1692044670367;4123.505179378494;4165.791710414528;4170.645495132948;10648.946755266174;10832.72918177036;10903.672889813319;10913.727156821144;11080.168987325822;10851.348919064882;10872.891271087346;10880.510377032893;10976.067034302014;11024.118070554336;8268.751937441886;8326.584480638307;8476.32189424857;8521.786955326166;8565.571721413904;5744.770209191585;6015.037593984942;6104;6127.172831667668;6187.195664563758;10146.239032072772;10181.33821301613;10184.981501849756;10253.846192306972;10272.589096436059;10130.696079117639;10225;10241.48792560354;10274.948625256826;10380.377177369382;7064.116985376828;7494.025776649219;7681.116671582839;7741.690332386642;7843.56860372685;7299.927000729925;8554.828903422022;8561.315094792526;8604.956975215084;8641.351898607503;7488.663010164664;7520.135184453858;7648;7672.846543069219;7754.495957762728;6216.502679785516;7411.62941852916;7430.034095567542;7475.0656167979005;7538.849223015619;7053.788386348418;7111.7155313788935;7144.607046612487;7154.034205382207;7185.281471852982;4763.095011947719;4782.617390608673;4906.36217291764;4929.112759703288;5092.8472145835685;4754.667173297836;4939.703617782944;4965.354503914468;5046.293518907282;5232.189010703347 05:50:49 INFO - PROCESS | 1661 | |4;prototype.html;268.39744772984653;271.1323763955343;277.68898833306685;316.13694613704587;327.27345293448536;4758.073784622458;5598.86002849929;5602.887942241158;5657.575681823864;5672.865426914618;4802.687825291362;5577.8047768328115;5608.971955140219;5633.267675202228;5637.633553818999;4042.8180731867137;4129.669626429878;4240.512341080769;4248.511421186557;4253.510846252674;3162.845561370095;4202.6007529284825;4241.978790106045;4260.936085958713;4277.572242775713;3519.5952465466544;3580.2839432113683;4184.7907604619795;4252.9367658085475;4272.230998420283;4041.6766658667234;4116;4148.128892932487;4263.936040959388;4289.313709806416;3630.8547658093644;3649.799261040642;3659.3779057560278;3688.0779805048737;3697.482352470656;4838.854834354975;5082.872928176788;5195.506426889439;5369.785208591652;5391.595630327741;4503.774811259424;4528.660350473695;4556.407667003302;4804.375431193957;4956.231784073474;5059.367579052619;5633.126865335838;5704;5849.824505264849;5879.382664820175;3613.692836108934;3694.1001313968404;4005.439238506611;4019.8392064317686;4027.8791636250958;5520.475554822286;5715.942840571583;6099;6219.906701399483;6273.780417685405;4021.3565829467143;4041.191761647682;4054.5742697016976;4068.6745060395097;4078.3270760324212;3495.545579074704;3655.5065066216252;3848.499695039627;4142.668586513072;4178.247915375262;4074.9812546863286;4402.119576084796;4522.163399771053;4773.498782627774;4814.855554333376;4666.556677115668;5374.596905232123;5951.583389162717;6028.457438830526;6033.698315084228;1288.7293668329569;1322.927239001864;1382.979255311171;1412.9152250864981;1413.9929300353433;778.6573907480804;848.3043903998662;863.468966585558;870.7866572689605;903.70629545398;4581.450225972904;5002.824901128516;5011.574016208663;5455.290812194389;5462.426445223274;5720.685362305113;5991.55063370249;5997.8004399120355;6042.516598672096;6088.6042407243385;4186.016286172808;4628.356933288701;4717.669763116617;4764.618830493552;5343.65266257692;2721.8502982335767;2821.4059056633346;2904.2739315171207;3162.652108268089;3164.054106724348;5877.441643043904;6280.842978925563;6302.306746257908;6344;6372.776952806629;5305.814296499604;5941.643501389931;6256.968715156486;6422.293547709748;6522.380373864476;5734.913976290359;6089.782043591211;6282.167877594664;6501.934980650228;6546.705398256994;5811.767529298781;6046.455818976313;6121.632702037892;6260.060990851409;6261.4364707174755;4855.81943826794;5213.687178769286;5895.29256489224;6217.502599791916;6234.844128896814;5843.503302219189;5969.253843269591;6020.020090055451;6481.254655714653;6520.804375868732;4022.4368588398233;4045.615666511677;4051.5616955980204;4066.072427851942;4079.449274347925;6189.752409903734;6203.689815509116;6206.875862482815;6227.283862355705;6240.032794916795;5708.601392658825;5950.910736339045;5987.832372687284;5990.580659353804;6071.539222643051;5092.541671249605;5418.864528386822;5769.769209231584;5958.106284057426;6021.578489505692;4779.761011949458;5617.7191140442;5796.82609521715;5829;5866.560007999332;4476.373307736984;5270.917987395034;5812.18629391877;5874.647521148745;6001.5798894077;5412.377576578744;5594.664320140804;5822.301323840997;5918.23063001807;5982.581219314607 05:50:49 INFO - PROCESS | 1661 | |5;yui.html;654.6432194454023;692.7679227458801;750.9324160825524;768.9000429944107;784.4195295481345;14812.111273323595;15146.24268786559;15222;15571.532854014384;15821.841781582181;14214.076085054465;14387.280635968185;14593.708125837504;14620.415183392679;14678.192699401528;738.7488253993641;847.3517758914436;904.2314033071885;924.5700749151644;938.6714649872524;935.349931797401;939.3612343606345;939.9248060155205;940.5861420974784;940.92472602192;976.8241716491028;979.7501637082534;986.4771671014374;988.6292640259902;988.9653862114827;962.7159987803615;976.599594166392;977.203579083045;980.691082309074;980.6959842448864;706.2055187913597;721.1058287723185;730.0327066636728;730.8501757139774;732.0848938826467;14895.702085958328;14948.40206391743;15179.392824287015;15199.164045977466;15256.542303730906;12732;12846.293453860035;12877.291748953803;12879.549215777497;12979.545715899898;10156.441395723232;10254.48727563619;10292.536835842406;10379.273450858444;10476.947615261875;3514.3147086318177;3541.769784963969;3555.520004799345;3601.693856022241;3649.5255616769914;83205.16794832035;84021;84436;86240.56879715624;86242.99999999937;8443.831123377498;9616.894057183374;9633.470159141174;9636.951815240878;9933.850992235122;22592.435189120402;22629.88685056597;22646.547069058528;22683.773162268495;22757.886210568842;7404.867333601689;7467.290607392289;7504.887426688604;7533.8116547086765;7831.2560306770765;4003.959960400359;4038.697097717683;4054.4526488923616;4060.451839001756;4237.491501019897;3597.37046016946;3678.3562876496508;3679.7608155469807;3706;3713.350163721338;623.7255607532671;627.4823270801606;628.2115944489636;649.5096202367276;651.1697585578331;549.3957643680462;552.2379116818845;572.2389222334285;595.8986972214648;609.1654433426235;6880.827979300558;7722.073351197892;8068.951036365235;8104.432689711663;8206.056303525052;499.0991260774265;524.9028929648027;531.989220481089;537.3846945247669;579.8144593729966;3860.324443222481;3934.11482416454;3979.7612143271494;4026.9597304026584;4032.475778148822;91190.54404727934;98237.50881245547;98421.50789246151;98673;99982;78279;80263.59868200739;81360.18639813527;83859;84283;71951.68289902623;72135.55728885393;72753.2724672757;72816.27183728201;73004.26995730081;7530.359919406693;7566.205548417557;7698.230176982257;7816.4137689674635;7835.84328313442;46318.53681463142;46647;47115;47442.28836567385;47604.80987975328;65741.67129164323;65868;70060.64969675119;70109.64945175241;71354.57290854257;9750.512474376395;11626.76746465083;11803.173777835469;12109.524116424993;12251;8146.714864979697;10160.441175735405;10545.525451354553;10731.517081731497;10867.13062954946;11711.238769479956;11748.530058797895;11943.761124777282;12021.879781202077;12083;11212.327260364404;11683.88316116862;11837.585684501;12066.155369124077;12391.62825115248;10464.843027354747;11755.88244117548;12047.759044818878;12141.332226727614;12307.384630768605;7901.762947111596;8514.063453020039;9624.181944534794;9970.55132519024;10054.64808731691;8155.632996515053;8993.415427997159;9900.112976354505;9906.356086854332;9984.500774961369 05:50:49 INFO - PROCESS | 1661 | __end_tp_report 05:50:49 INFO - PROCESS | 1661 | __start_cc_report 05:50:49 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,422 05:50:49 INFO - PROCESS | 1661 | __end_cc_report 05:50:49 INFO - PROCESS | 1661 | __startTimestamp1463489449942__endTimestamp 05:50:49 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | ------- Summary: start ------- 05:50:49 INFO - PROCESS | 1661 | Number of tests: 6 05:50:49 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5777.83 Median:6107.77 stddev:802.64 (13.1%) stddev-sans-first:772.95 05:50:49 INFO - PROCESS | 1661 | Values: 2910.9 2949.6 3473.7 3528.2 3558.5 4836.8 5575.4 5866.1 5896.7 5927.5 4488.5 4932.7 5862.0 5922.5 5954.1 5127.6 5179.9 5259.0 5352.6 5407.3 262.0 4957.4 5260.3 5353.3 5415.9 5323.2 5330.2 5349.3 5354.9 5389.4 5338.7 5360.9 5396.8 5401.4 5410.7 5191.0 5227.9 5296.3 5301.2 5337.3 5761.3 5829.2 5855.7 5880.4 5902.0 6167.8 6172.5 6236.0 6252.6 6256.2 6181.2 6230.9 6237.8 6307.8 6309.2 6026.6 6051.3 6062.3 6063.7 6067.7 5605.8 6200.1 6351.5 6390.3 6479.4 6146.5 6200.8 6205.9 6217.5 6234.4 6282.6 6283.8 6319.5 6335.1 6342.4 6106.7 6120.0 6129.1 6200.9 6253.8 6124.0 6155.0 6168.2 6168.9 6171.1 4952.6 5010.9 5023.6 5025.4 5025.8 4398.8 4488.8 4508.8 4546.6 4589.2 5914.1 5963.1 6009.7 6015.7 6055.6 6315.2 6326.1 6349.4 6364.2 6385.6 5889.1 5984.9 6003.7 6032.2 6051.5 5430.5 5457.2 5462.9 5482.6 5513.1 6340.0 6460.5 6474.6 6483.8 6504.1 6247.0 6260.0 6301.7 6384.3 6386.4 5387.6 6523.1 6529.4 6566.4 6570.2 6107.8 6132.0 6242.8 6256.9 6265.7 6188.0 6276.9 6329.1 6337.2 6352.0 4702.4 6183.3 6190.4 6199.0 6212.4 6124.5 6143.1 6161.4 6186.7 6198.2 6067.6 6150.2 6156.9 6185.7 6192.4 6159.5 6170.5 6185.8 6189.5 6192.2 6057.3 6105.3 6114.3 6132.2 6183.6 05:50:49 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:73163.32 Median:39387.80 stddev:165916.10 (421.2%) stddev-sans-first:166291.85 05:50:49 INFO - PROCESS | 1661 | Values: 503.4 507.2 534.2 578.8 600.3 40276.2 42848.8 42901.4 42928.8 43070.0 43735.8 43928.0 44030.1 44326.0 44362.7 9571.4 9746.2 9787.1 9811.6 9826.9 8104.1 8961.7 9324.3 9669.5 9799.2 8261.6 9747.3 9759.3 9801.5 9812.8 9722.1 9728.2 9767.6 9773.5 9782.3 8736.7 9581.3 9668.8 9676.6 9725.1 32747.5 39524.6 40644.0 40649.2 40787.8 2771.7 2967.4 3083.0 3207.1 3226.1 27251.0 27522.6 29291.0 29552.4 29946.7 2425.8 2564.9 2638.6 2662.3 2862.9 539150.0 544932.0 546406.0 548084.3 548922.0 34850.7 67589.7 68007.0 68117.7 68147.7 36328.3 36521.6 36630.8 36735.1 36799.4 15575.5 15660.2 15689.0 15816.3 15860.0 12958.7 12983.2 13004.2 13051.7 13079.4 8189.3 8311.7 8324.1 8324.5 8387.5 2967.3 3419.3 3473.1 3491.2 3505.4 988.5 990.8 1031.9 1061.1 1063.4 25087.6 31164.1 31491.5 31661.5 32440.0 1116.9 1134.3 1149.8 1169.8 1256.9 14950.9 15534.3 15789.8 15803.5 15830.0 575905.0 688896.0 695020.0 704013.0 710702.0 62112.4 62264.7 62485.4 62570.4 62672.7 1265.2 1462.9 1494.5 1519.4 1524.1 29428.3 29529.0 29593.4 30037.5 30287.5 51705.0 52462.5 52640.0 53320.2 53468.7 622630.0 623630.0 627782.0 628599.0 629500.0 22748.2 25088.9 25212.6 25688.4 26469.6 47591.0 48294.3 48463.5 49076.8 49319.0 31977.5 39303.8 39659.8 40113.8 40246.2 37476.1 38091.4 38415.0 38894.4 39202.4 38113.0 38383.8 39205.4 39333.0 39442.6 25068.6 33833.7 34312.6 34334.3 35292.1 20261.4 25284.0 25610.4 25655.4 25799.5 05:50:49 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35838.51 Median:11468.81 stddev:100733.22 (878.3%) stddev-sans-first:100980.64 05:50:49 INFO - PROCESS | 1661 | Values: 390.9 396.9 397.2 398.6 418.5 14685.7 19555.8 20421.5 20751.9 20781.7 20172.6 20669.5 21152.7 21157.7 21171.7 5333.4 5931.2 6118.7 6127.8 6132.9 5829.3 6028.4 6041.4 6077.9 6117.0 5991.2 6054.3 6057.4 6073.9 6082.6 5511.0 5898.3 5932.1 6045.5 6055.1 4225.6 5710.6 5749.3 5764.8 5779.0 25408.6 25844.5 26366.7 26403.9 26405.2 2016.0 2059.4 2088.7 2175.3 2463.5 8824.9 9272.0 9709.6 9830.4 10208.1 1787.7 1789.5 1792.9 1823.1 1826.3 114584.0 115859.4 120588.8 130214.0 130521.3 11702.4 11761.7 11908.0 11971.3 12062.3 15192.3 15309.4 15361.6 15410.9 15480.4 8855.7 8913.4 8926.4 8943.6 9047.7 7461.3 8828.6 8845.4 8918.4 8926.6 4710.2 4721.0 4747.7 4781.1 4805.3 1892.0 2043.6 2049.2 2065.3 2077.5 815.9 851.2 890.5 912.4 962.6 20233.6 20569.9 20674.6 20848.9 20918.8 795.6 871.6 891.0 911.9 920.7 9102.5 10714.7 10793.7 10857.7 11086.7 563946.0 598906.0 605211.0 619632.0 620289.0 131322.4 134706.3 135877.3 135990.0 140656.9 1013.8 1044.8 1051.8 1052.8 1056.1 17763.5 18762.4 19077.3 19288.7 19297.0 47286.0 51530.2 53527.0 55956.0 57140.0 77242.2 77418.0 81690.0 86144.0 86675.0 11639.7 11788.5 11917.9 11971.8 12113.9 12991.0 13198.9 13329.5 13399.4 13481.5 11262.1 11270.0 11406.3 11538.8 11573.5 11176.7 11177.0 11204.8 11248.7 11393.7 9346.4 11636.6 11780.5 11853.6 11921.7 10717.7 11965.6 12160.7 12178.0 12181.8 9257.0 10983.9 11097.8 11404.4 11408.7 05:50:49 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5805.71 Median:4844.03 stddev:3236.94 (66.8%) stddev-sans-first:3230.73 05:50:49 INFO - PROCESS | 1661 | Values: 1618.5 1653.2 1772.6 1912.7 1929.4 8029.9 8623.9 8672.2 9154.0 9208.7 8700.4 8770.3 8868.6 8923.7 8924.6 2432.6 2466.8 2467.2 2467.4 2468.6 1850.0 2268.8 2415.2 2456.1 2488.5 2450.4 2456.0 2458.7 2469.2 2490.7 2416.6 2432.7 2437.3 2452.7 2462.5 1951.9 2120.8 2356.4 2369.8 2371.5 7404.2 8881.4 8978.3 8982.6 8985.0 5357.5 5481.5 5484.1 5520.6 5538.5 3512.4 3555.4 3568.8 3629.3 3633.5 997.2 1001.2 1006.9 1021.7 1035.9 11637.5 11742.1 11750.9 11756.7 11783.4 4808.7 4836.6 4921.7 4941.9 4942.3 10140.7 10144.5 10175.9 10185.9 10192.3 5435.8 5469.9 5514.5 5546.6 5572.1 4492.2 4851.4 5051.8 5054.9 5060.8 4110.5 4134.5 4166.8 4170.4 4206.1 1351.2 1358.6 1375.0 1381.0 1387.3 861.0 882.7 901.8 932.5 943.9 5564.3 5853.2 5972.3 5985.2 6163.4 925.2 941.7 969.1 982.2 1016.3 3929.9 3956.2 4123.5 4165.8 4170.6 10648.9 10832.7 10903.7 10913.7 11080.2 10851.3 10872.9 10880.5 10976.1 11024.1 8268.8 8326.6 8476.3 8521.8 8565.6 5744.8 6015.0 6104.0 6127.2 6187.2 10146.2 10181.3 10185.0 10253.8 10272.6 10130.7 10225.0 10241.5 10274.9 10380.4 7064.1 7494.0 7681.1 7741.7 7843.6 7299.9 8554.8 8561.3 8605.0 8641.4 7488.7 7520.1 7648.0 7672.8 7754.5 6216.5 7411.6 7430.0 7475.1 7538.8 7053.8 7111.7 7144.6 7154.0 7185.3 4763.1 4782.6 4906.4 4929.1 5092.8 4754.7 4939.7 4965.4 5046.3 5232.2 05:50:49 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4742.15 Median:5324.73 stddev:1509.01 (28.3%) stddev-sans-first:1475.41 05:50:49 INFO - PROCESS | 1661 | Values: 268.4 271.1 277.7 316.1 327.3 4758.1 5598.9 5602.9 5657.6 5672.9 4802.7 5577.8 5609.0 5633.3 5637.6 4042.8 4129.7 4240.5 4248.5 4253.5 3162.8 4202.6 4242.0 4260.9 4277.6 3519.6 3580.3 4184.8 4252.9 4272.2 4041.7 4116.0 4148.1 4263.9 4289.3 3630.9 3649.8 3659.4 3688.1 3697.5 4838.9 5082.9 5195.5 5369.8 5391.6 4503.8 4528.7 4556.4 4804.4 4956.2 5059.4 5633.1 5704.0 5849.8 5879.4 3613.7 3694.1 4005.4 4019.8 4027.9 5520.5 5715.9 6099.0 6219.9 6273.8 4021.4 4041.2 4054.6 4068.7 4078.3 3495.5 3655.5 3848.5 4142.7 4178.2 4075.0 4402.1 4522.2 4773.5 4814.9 4666.6 5374.6 5951.6 6028.5 6033.7 1288.7 1322.9 1383.0 1412.9 1414.0 778.7 848.3 863.5 870.8 903.7 4581.5 5002.8 5011.6 5455.3 5462.4 5720.7 5991.6 5997.8 6042.5 6088.6 4186.0 4628.4 4717.7 4764.6 5343.7 2721.9 2821.4 2904.3 3162.7 3164.1 5877.4 6280.8 6302.3 6344.0 6372.8 5305.8 5941.6 6257.0 6422.3 6522.4 5734.9 6089.8 6282.2 6501.9 6546.7 5811.8 6046.5 6121.6 6260.1 6261.4 4855.8 5213.7 5895.3 6217.5 6234.8 5843.5 5969.3 6020.0 6481.3 6520.8 4022.4 4045.6 4051.6 4066.1 4079.4 6189.8 6203.7 6206.9 6227.3 6240.0 5708.6 5950.9 5987.8 5990.6 6071.5 5092.5 5418.9 5769.8 5958.1 6021.6 4779.8 5617.7 5796.8 5829.0 5866.6 4476.4 5270.9 5812.2 5874.6 6001.6 5412.4 5594.7 5822.3 5918.2 5982.6 05:50:49 INFO - PROCESS | 1661 | 05:50:49 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:18697.33 Median:627.85 stddev:26721.36 (4256.0%) stddev-sans-first:26761.97 05:50:49 INFO - PROCESS | 1661 | Values: 654.6 692.8 750.9 768.9 784.4 14812.1 15146.2 15222.0 15571.5 15821.8 14214.1 14387.3 14593.7 14620.4 14678.2 738.7 847.4 904.2 924.6 938.7 935.3 939.4 939.9 940.6 940.9 976.8 979.8 986.5 988.6 989.0 962.7 976.6 977.2 980.7 980.7 706.2 721.1 730.0 730.9 732.1 14895.7 14948.4 15179.4 15199.2 15256.5 12732.0 12846.3 12877.3 12879.5 12979.5 10156.4 10254.5 10292.5 10379.3 10476.9 3514.3 3541.8 3555.5 3601.7 3649.5 83205.2 84021.0 84436.0 86240.6 86243.0 8443.8 9616.9 9633.5 9637.0 9933.9 22592.4 22629.9 22646.5 22683.8 22757.9 7404.9 7467.3 7504.9 7533.8 7831.3 4004.0 4038.7 4054.5 4060.5 4237.5 3597.4 3678.4 3679.8 3706.0 3713.4 623.7 627.5 628.2 649.5 651.2 549.4 552.2 572.2 595.9 609.2 6880.8 7722.1 8069.0 8104.4 8206.1 499.1 524.9 532.0 537.4 579.8 3860.3 3934.1 3979.8 4027.0 4032.5 91190.5 98237.5 98421.5 98673.0 99982.0 78279.0 80263.6 81360.2 83859.0 84283.0 71951.7 72135.6 72753.3 72816.3 73004.3 7530.4 7566.2 7698.2 7816.4 7835.8 46318.5 46647.0 47115.0 47442.3 47604.8 65741.7 65868.0 70060.6 70109.6 71354.6 9750.5 11626.8 11803.2 12109.5 12251.0 8146.7 10160.4 10545.5 10731.5 10867.1 11711.2 11748.5 11943.8 12021.9 12083.0 11212.3 11683.9 11837.6 12066.2 12391.6 10464.8 11755.9 12047.8 12141.3 12307.4 7901.8 8514.1 9624.2 9970.6 10054.6 8155.6 8993.4 9900.1 9906.4 9984.5 05:50:49 INFO - PROCESS | 1661 | -------- Summary: end -------- 05:50:49 INFO - PROCESS | 1661 | 05:50:50 INFO - TEST-INFO | 1661: exit 0 05:50:50 INFO - TEST-OK | dromaeo_css | took 1112150ms 05:50:50 INFO - TEST-START | kraken 05:50:50 INFO - operating with platform_type : mac_ 05:50:50 INFO - Initialising browser for kraken test... 05:50:50 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpgvVRhL/profile http://localhost:49271/getInfo.html) 05:50:53 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 05:50:53 INFO - PROCESS | 1673 | colorDepth:24 05:50:53 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 05:50:53 INFO - PROCESS | 1673 | __metrics 05:50:53 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:50:53 INFO - TEST-INFO | 1673: exit 0 05:50:53 INFO - Browser initialized. 05:50:53 INFO - Running cycle 1/1 for kraken test... 05:50:53 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpgvVRhL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:05 INFO - PROCESS | 1676 | RSS: Main: 250126336 05:51:05 INFO - PROCESS | 1676 | 05:51:08 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:09 INFO - PROCESS | 1676 | RSS: Main: 262156288 05:51:09 INFO - PROCESS | 1676 | 05:51:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:51:13 INFO - PROCESS | 1676 | RSS: Main: 266846208 05:51:13 INFO - PROCESS | 1676 | 05:51:16 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:51:17 INFO - PROCESS | 1676 | RSS: Main: 267681792 05:51:17 INFO - PROCESS | 1676 | 05:51:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:51:19 INFO - PROCESS | 1676 | RSS: Main: 268288000 05:51:19 INFO - PROCESS | 1676 | 05:51:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:22 INFO - PROCESS | 1676 | RSS: Main: 268722176 05:51:22 INFO - PROCESS | 1676 | 05:51:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:26 INFO - PROCESS | 1676 | RSS: Main: 268378112 05:51:26 INFO - PROCESS | 1676 | 05:51:29 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:30 INFO - PROCESS | 1676 | RSS: Main: 268730368 05:51:30 INFO - PROCESS | 1676 | 05:51:33 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:51:33 INFO - PROCESS | 1676 | RSS: Main: 268652544 05:51:33 INFO - PROCESS | 1676 | 05:51:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:36 INFO - PROCESS | 1676 | RSS: Main: 268431360 05:51:36 INFO - PROCESS | 1676 | 05:51:38 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:38 INFO - PROCESS | 1676 | RSS: Main: 268541952 05:51:38 INFO - PROCESS | 1676 | 05:51:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:41 INFO - PROCESS | 1676 | RSS: Main: 268873728 05:51:41 INFO - PROCESS | 1676 | 05:51:44 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:45 INFO - PROCESS | 1676 | RSS: Main: 268951552 05:51:45 INFO - PROCESS | 1676 | 05:51:48 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:48 INFO - PROCESS | 1676 | RSS: Main: 268955648 05:51:48 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:51:50 INFO - PROCESS | 1676 | RSS: Main: 270700544 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | __start_tp_report 05:51:50 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 05:51:50 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 05:51:50 INFO - PROCESS | 1676 | |i|pagename|runs| 05:51:50 INFO - PROCESS | 1676 | |0;ai-astar;131;115;107;116;116;114;105;115;114;114 05:51:50 INFO - PROCESS | 1676 | |1;audio-beat-detection;152;123;153;251;109;166;168;155;227;123 05:51:50 INFO - PROCESS | 1676 | |2;audio-dft;191;201;210;225;193;196;196;195;197;236 05:51:50 INFO - PROCESS | 1676 | |3;audio-fft;83;116;107;196;50;76;87;96;129;58 05:51:50 INFO - PROCESS | 1676 | |4;audio-oscillator;80;81;151;64;81;98;154;119;84;117 05:51:50 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;89;86;89;85;89;85;88;87;88;86 05:51:50 INFO - PROCESS | 1676 | |6;imaging-darkroom;126;105;153;151;95;95;103;96;94;94 05:51:50 INFO - PROCESS | 1676 | |7;imaging-desaturate;96;98;93;97;94;92;92;93;95;91 05:51:50 INFO - PROCESS | 1676 | |8;json-parse-financial;58;57;60;59;59;57;58;119;59;58 05:51:50 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;62;79;99;108;75;75;71;101;71;68 05:51:50 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;85;87;89;89;113;82;85;84;85;84 05:51:50 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;151;127;144;144;148;132;135;147;249;159 05:51:50 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;162;167;159;161;158;163;164;166;163;164 05:51:50 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;66;65;67;64;67;66;64;67;64;73 05:51:50 INFO - PROCESS | 1676 | __end_tp_report 05:51:50 INFO - PROCESS | 1676 | __start_cc_report 05:51:50 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1072 05:51:50 INFO - PROCESS | 1676 | __end_cc_report 05:51:50 INFO - PROCESS | 1676 | __startTimestamp1463489510814__endTimestamp 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | ------- Summary: start ------- 05:51:50 INFO - PROCESS | 1676 | Number of tests: 14 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:6.86 (6.0%) stddev-sans-first:4.01 05:51:50 INFO - PROCESS | 1676 | Values: 131.0 115.0 107.0 116.0 116.0 114.0 105.0 115.0 114.0 114.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:162.70 Median:160.50 stddev:45.06 (28.1%) stddev-sans-first:47.63 05:51:50 INFO - PROCESS | 1676 | Values: 152.0 123.0 153.0 251.0 109.0 166.0 168.0 155.0 227.0 123.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:204.00 Median:199.00 stddev:15.12 (7.6%) stddev-sans-first:15.29 05:51:50 INFO - PROCESS | 1676 | Values: 191.0 201.0 210.0 225.0 193.0 196.0 196.0 195.0 197.0 236.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:99.80 Median:67.00 stddev:41.73 (62.3%) stddev-sans-first:43.82 05:51:50 INFO - PROCESS | 1676 | Values: 83.0 116.0 107.0 196.0 50.0 76.0 87.0 96.0 129.0 58.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:102.90 Median:80.50 stddev:31.20 (38.8%) stddev-sans-first:31.97 05:51:50 INFO - PROCESS | 1676 | Values: 80.0 81.0 151.0 64.0 81.0 98.0 154.0 119.0 84.0 117.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.62 (1.8%) stddev-sans-first:1.58 05:51:50 INFO - PROCESS | 1676 | Values: 89.0 86.0 89.0 85.0 89.0 85.0 88.0 87.0 88.0 86.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:111.20 Median:94.00 stddev:23.58 (25.1%) stddev-sans-first:24.39 05:51:50 INFO - PROCESS | 1676 | Values: 126.0 105.0 153.0 151.0 95.0 95.0 103.0 96.0 94.0 94.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.50 stddev:2.33 (2.5%) stddev-sans-first:2.37 05:51:50 INFO - PROCESS | 1676 | Values: 96.0 98.0 93.0 97.0 94.0 92.0 92.0 93.0 95.0 91.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:58.50 stddev:19.21 (32.8%) stddev-sans-first:20.23 05:51:50 INFO - PROCESS | 1676 | Values: 58.0 57.0 60.0 59.0 59.0 57.0 58.0 119.0 59.0 58.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:80.90 Median:73.00 stddev:15.84 (21.7%) stddev-sans-first:15.26 05:51:50 INFO - PROCESS | 1676 | Values: 62.0 79.0 99.0 108.0 75.0 75.0 71.0 101.0 71.0 68.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.00 stddev:8.96 (10.5%) stddev-sans-first:9.42 05:51:50 INFO - PROCESS | 1676 | Values: 85.0 87.0 89.0 89.0 113.0 82.0 85.0 84.0 85.0 84.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:153.60 Median:147.50 stddev:34.83 (23.6%) stddev-sans-first:36.93 05:51:50 INFO - PROCESS | 1676 | Values: 151.0 127.0 144.0 144.0 148.0 132.0 135.0 147.0 249.0 159.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:163.50 stddev:2.83 (1.7%) stddev-sans-first:2.99 05:51:50 INFO - PROCESS | 1676 | Values: 162.0 167.0 159.0 161.0 158.0 163.0 164.0 166.0 163.0 164.0 05:51:50 INFO - PROCESS | 1676 | 05:51:50 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:2.67 (4.0%) stddev-sans-first:2.83 05:51:50 INFO - PROCESS | 1676 | Values: 66.0 65.0 67.0 64.0 67.0 66.0 64.0 67.0 64.0 73.0 05:51:50 INFO - PROCESS | 1676 | -------- Summary: end -------- 05:51:50 INFO - PROCESS | 1676 | 05:51:51 INFO - TEST-INFO | 1676: exit 0 05:51:51 INFO - TEST-OK | kraken | took 61099ms 05:51:51 INFO - SUITE-END | took 1173s 05:51:51 INFO - Completed test suite (00:19:33) 05:51:51 INFO - javascript benchmark 05:51:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7269.4461378969945, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2910.8690108945098, 2949.5723120147572, 3473.7221022318217, 3528.206153615437, 3558.5195998540207, 4836.782344794484, 5575.358833734122, 5866.120081987704, 5896.705164741762, 5927.525797936166, 4488.461384633841, 4932.704037757737, 5861.970690146554, 5922.466977971982, 5954.106883967396, 5127.564157046656, 5179.948200517993, 5258.973705131469, 5352.57179425645, 5407.324084489439, 261.9817124745141, 4957.398828113952, 5260.289860868772, 5353.330833645794, 5415.891682166374, 5323.22813192085, 5330.200469929503, 5349.251104845324, 5354.946450535484, 5389.434109418514, 5338.679679219259, 5360.865978350534, 5396.811111611075, 5401.405845357008, 5410.729463526808, 5191.039657663307, 5227.895442091175, 5296.311479507678, 5301.1783173608155, 5337.279467271907, 5761.308642962871, 5829.242198514208, 5855.707214639251, 5880.441358070976, 5902.0, 6167.814965551041, 6172.47533959614, 6236.0, 6252.562320637558, 6256.249250090018, 6181.196444462236, 6230.906536401958, 6237.812865614039, 6307.779227727054, 6309.242890853081, 6026.608270462406, 6051.273847138327, 6062.272527296708, 6063.666498342589, 6067.696535518577, 5605.80379686709, 6200.100985357189, 6351.460125889259, 6390.265119511223, 6479.384458476437, 6146.5390095743, 6200.81397558074, 6205.875882482415, 6217.502599792006, 6234.376562343729, 6282.560220784501, 6283.811485655347, 6319.52603554726, 6335.11308416822, 6342.429181373606, 6106.703363853616, 6120.049813200498, 6129.083728908246, 6200.875982480326, 6253.812385628438, 6123.953766440866, 6155.0, 6168.228971378578, 6168.8766224675255, 6171.13604095427, 4952.5542701157065, 5010.924836127461, 5023.598112151019, 5025.3718285214345, 5025.7989680412375, 4398.758068306274, 4488.753118578445, 4508.842190523316, 4546.568076032771, 4589.2198326284615, 5914.112883067488, 5963.135345374896, 6009.669468179205, 6015.729292181863, 6055.636661800306, 6315.179026726496, 6326.051092336186, 6349.365063493706, 6364.204474440695, 6385.553011289165, 5889.146073819272, 5984.910226346608, 6003.729832157476, 6032.155498230251, 6051.546134039964, 5430.456954304617, 5457.181422786613, 5462.918056229159, 5482.5613950883835, 5513.09034009392, 6340.0, 6460.483161347081, 6474.643894585843, 6483.805485835433, 6504.089427480157, 6247.00047992319, 6260.0, 6301.684915754286, 6384.265809431975, 6386.425221730066, 5387.595930305321, 6523.054157147282, 6529.379708927644, 6566.409023187745, 6570.211574611078, 6107.765388666889, 6132.04958718918, 6242.781502647385, 6256.937430625636, 6265.718042688181, 6187.969060154851, 6276.905846412216, 6329.1139240505445, 6337.2395312562785, 6352.047192921099, 4702.365180700562, 6183.258009038944, 6190.380961903774, 6198.969005154945, 6212.409821066992, 6124.47247923558, 6143.143438301211, 6161.353057928853, 6186.721597528032, 6198.194234749454, 6067.575269731076, 6150.231221097363, 6156.907646385218, 6185.659788711664, 6192.380761923771, 6159.538034647509, 6170.475509581556, 6185.752569897154, 6189.535784816067, 6192.195014648067, 6057.273127224761, 6105.308121371189, 6114.266954506276, 6132.202813634199, 6183.628982261079], "value": 5727.768169524255, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.3632454944495, 507.2011581758731, 534.1693666348829, 578.8350320158755, 600.3066458240734, 40276.19447611046, 42848.785756071215, 42901.35647965283, 42928.785356073175, 43070.0, 43735.78132109343, 43928.0, 44030.11939761203, 44326.0, 44362.66911992645, 9571.425714457155, 9746.220302375792, 9787.070228328334, 9811.607535698564, 9826.852597211011, 8104.1085480597085, 8961.731148065535, 9324.300677449184, 9669.468179250138, 9799.216062714966, 8261.628226729781, 9747.268954828382, 9759.316847820655, 9801.509924503782, 9812.803743925117, 9722.076402741764, 9728.17310528606, 9767.609295628166, 9773.462459564686, 9782.266330025277, 8736.737897863075, 9581.281403894736, 9668.75828104296, 9676.56455459506, 9725.0761177688, 32747.50878736821, 39524.60475395238, 40644.0, 40649.187016259806, 40787.79606101951, 2771.6951135375093, 2967.4065186962694, 3082.9845850770603, 3207.134073800084, 3226.1450715560395, 27251.046213382633, 27522.587161192598, 29291.0, 29552.408951821057, 29946.700532994608, 2425.847722331896, 2564.9487010259877, 2638.5514462541414, 2662.294491959632, 2862.8854845806145, 539150.0, 544932.0, 546406.0, 548084.2595787036, 548922.0, 34850.651493484744, 67589.66205168943, 68006.97989530161, 68117.65941170361, 68147.65926170337, 36328.27343453116, 36521.63478365236, 36630.81684591613, 36735.08162295911, 36799.4480082799, 15575.454859079879, 15660.216989150496, 15689.0, 15816.28826702801, 15860.04839709621, 12958.676033099247, 12983.221006739626, 13004.21974681522, 13051.738965220642, 13079.41142648583, 8189.262966333059, 8311.672731635152, 8324.125966773401, 8324.500529968222, 8387.454815436977, 2967.2581854536365, 3419.316136772655, 3473.114355839245, 3491.2319289756215, 3505.386557352453, 988.5304480371766, 990.8365119755159, 1031.9277650564388, 1061.0662616897234, 1063.3672964586058, 25087.62368564473, 31164.065078047694, 31491.527627085612, 31661.52507712386, 32440.0, 1116.8771435142128, 1134.3080720760329, 1149.83327417524, 1169.8128299472214, 1256.9057320700983, 14950.850491495163, 15534.300956456986, 15789.842101579066, 15803.525894223192, 15829.97105188159, 575905.0, 688896.0, 695020.0, 704013.0, 710702.0, 62112.378876211566, 62264.688676556325, 62485.375146247956, 62570.37429625737, 62672.68663656653, 1265.2155663488695, 1462.9195394253206, 1494.5441640299596, 1519.3770554072778, 1524.0931645670807, 29428.264293392836, 29529.0, 29593.40813183681, 30037.54943675803, 30287.545686814276, 51705.0, 52462.47537524728, 52640.0, 53320.2001969963, 53468.73265633647, 622630.0, 623630.0, 627782.0, 628599.0, 629500.0, 22748.20381286647, 25088.874555627106, 25212.621810673223, 25688.357791055372, 26469.602955956063, 47591.0, 48294.27558586694, 48463.5153648459, 49076.75461622669, 49319.0, 31977.520337194495, 39303.80348098241, 39659.80170099131, 40113.799431002655, 40246.1950760989, 37476.06309842276, 38091.42862857004, 38415.03962400962, 38894.41658375183, 39202.411963821134, 38113.0, 38383.80808095942, 39205.41191882181, 39333.0, 39442.60557394504, 25068.62397064082, 33833.66166338404, 34312.62749489993, 34334.313313734085, 35292.0589367577, 20261.392158235278, 25284.0, 25610.359741006625, 25655.358616034002, 25799.484010320062], "value": 18932.565100853935, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.906182516196, 396.93450580654195, 397.167933179988, 398.61931855078404, 418.5416968419581, 14685.706285874276, 19555.826650400984, 20421.48946276344, 20751.858647774385, 20781.688274675893, 20172.59654806903, 20669.483262918435, 21152.682709759327, 21157.68263476049, 21171.682424763603, 5333.4399888011785, 5931.199288096116, 6118.69406529674, 6127.846803829895, 6132.908006379907, 5829.329627092875, 6028.4272994065495, 6041.365656606059, 6077.939220607804, 6117.0, 5991.221141251631, 6054.334023257438, 6057.363976782441, 6073.878522429549, 6082.5742198046155, 5511.0, 5898.262717160355, 5932.11018347247, 6045.455908968192, 6055.091736239578, 4225.556316586745, 5710.600257981943, 5749.31008279005, 5764.827055188351, 5778.971105144489, 25408.618870716953, 25844.48311033769, 26366.73633263681, 26403.867980659972, 26405.207843764718, 2016.0, 2059.3718915730697, 2088.718023066882, 2175.3039027511204, 2463.458039231384, 8824.911750882537, 9271.953640231823, 9709.61161553537, 9830.361026533252, 10208.132308753764, 1787.731840223964, 1789.4810504953548, 1792.9103544822838, 1823.124900047971, 1826.3060037185785, 114584.0, 115859.4207028968, 120588.79411205863, 130214.0, 130521.34739326338, 11702.356370399539, 11761.705957350963, 11908.0, 11971.281723096468, 12062.311276421307, 15192.316345764468, 15309.387624494895, 15361.6159596011, 15410.922945385426, 15480.380784768708, 8855.697518645198, 8913.420627659181, 8926.375153739176, 8943.642254309754, 9047.728568142966, 7461.291177338144, 8828.558572071372, 8845.42504737203, 8918.375713700108, 8926.553672316359, 4710.152172608895, 4720.976395118002, 4747.667663263538, 4781.091592597465, 4805.255185446262, 1891.990540047291, 2043.6117137743781, 2049.2069439240663, 2065.2874758208395, 2077.542940553076, 815.8903890708632, 851.1913682002123, 890.4746199742183, 912.379581884325, 962.5908988679784, 20233.595328093357, 20569.8686572237, 20674.58650827005, 20848.895755521124, 20918.79081209199, 795.5783434779576, 871.6252011634931, 890.9910900891037, 911.8541033434751, 920.6593560382702, 9102.499362535124, 10714.678559643224, 10793.730156746144, 10857.728556785985, 11086.667399978014, 563946.0, 598906.0, 605211.0, 619632.0, 620289.0, 131322.3735525284, 134706.32646836704, 135877.32061339827, 135990.0, 140656.89014664787, 1013.7566983923953, 1044.8171569975225, 1051.8317069269033, 1052.7999680060916, 1056.0970370333407, 17763.46709598714, 18762.437126886216, 19077.332293369665, 19288.710669340253, 19297.035148242252, 47286.0, 51530.22704659508, 53527.0, 55956.0, 57140.0, 77242.22757772575, 77418.0, 81690.0, 86144.0, 86675.0, 11639.6508104757, 11788.528458861549, 11917.880821192024, 11971.820422693492, 12113.878861211628, 12991.0, 13198.868011320148, 13329.533466328632, 13399.397027133607, 13481.528146514825, 11262.099032077578, 11270.0, 11406.315621062762, 11538.826917596074, 11573.537058517564, 11176.664700059011, 11177.0, 11204.775904482029, 11248.718782030515, 11393.715157120807, 9346.392484488486, 11636.592719254786, 11780.469878855301, 11853.5851245206, 11921.701957451132, 10717.678469645924, 11965.581204657796, 12160.695982600506, 12178.0, 12181.817272740738, 9257.0, 10983.890161098287, 11097.778044439228, 11404.372759498307, 11408.657740267805], "value": 9869.057621312313, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1618.4901755946876, 1653.156889986107, 1772.5568607848038, 1912.665283575374, 1929.3633101076646, 8029.9197008029905, 8623.870641940375, 8672.176143266388, 9153.954230228848, 9208.723738287845, 8700.43447175933, 8770.254528365096, 8868.556572171397, 8923.732288031353, 8924.59839307231, 2432.562138815017, 2466.839655422392, 2467.247489515697, 2467.4078221226864, 2468.6420469031978, 1849.953751156225, 2268.820213488985, 2415.1909110448023, 2456.103522214388, 2488.464980029293, 2450.3996520852415, 2456.005317846275, 2458.7418321076298, 2469.1975108089855, 2490.663760392342, 2416.6375043743406, 2432.6837511123617, 2437.2688193541953, 2452.681151450318, 2462.5321188974217, 1951.882887026783, 2120.801747012948, 2356.4344557306295, 2369.834111612188, 2371.513839662865, 7404.1855395906405, 8881.378303518757, 8978.281737460988, 8982.550872456351, 8985.011648718635, 5357.544608708265, 5481.506664400223, 5484.095124304525, 5520.641158324696, 5538.529225015878, 3512.367773800715, 3555.3600351936643, 3568.80371579563, 3629.2922880038404, 3633.5458067741533, 997.2072202598952, 1001.2190491416634, 1006.8842083160457, 1021.667957913681, 1035.8653375061194, 11637.47631356591, 11742.060635149168, 11750.882491175149, 11756.706082347924, 11783.410829458493, 4808.735519546389, 4836.637252206098, 4921.655484116149, 4941.901161976741, 4942.332785073969, 10140.695779126638, 10144.492775361203, 10175.9491202545, 10185.94907025475, 10192.337498062601, 5435.83692489226, 5469.890602187934, 5514.476124768141, 5546.639468434539, 5572.08060669993, 4492.191405547033, 4851.417829860368, 5051.82318618854, 5054.9241761373605, 5060.822871199491, 4110.506739191256, 4134.545200027995, 4166.7708276045105, 4170.395292682544, 4206.095689426788, 1351.1892864281353, 1358.6127953533196, 1374.9518766843112, 1381.0470775165104, 1387.257817067864, 861.0356400831109, 882.7351794461636, 901.7655409593422, 932.4917919733762, 943.8725772020689, 5564.33228012641, 5853.151293062482, 5972.313183983811, 5985.221921150222, 6163.414475624809, 925.1627277313908, 941.6751220828803, 969.1471505075489, 982.2289502740315, 1016.3444578246989, 3929.901752456154, 3956.1692044670367, 4123.505179378494, 4165.791710414528, 4170.645495132948, 10648.946755266174, 10832.72918177036, 10903.672889813319, 10913.727156821144, 11080.168987325822, 10851.348919064882, 10872.891271087346, 10880.510377032893, 10976.067034302014, 11024.118070554336, 8268.751937441886, 8326.584480638307, 8476.32189424857, 8521.786955326166, 8565.571721413904, 5744.770209191585, 6015.037593984942, 6104.0, 6127.172831667668, 6187.195664563758, 10146.239032072772, 10181.33821301613, 10184.981501849756, 10253.846192306972, 10272.589096436059, 10130.696079117639, 10225.0, 10241.48792560354, 10274.948625256826, 10380.377177369382, 7064.116985376828, 7494.025776649219, 7681.116671582839, 7741.690332386642, 7843.56860372685, 7299.927000729925, 8554.828903422022, 8561.315094792526, 8604.956975215084, 8641.351898607503, 7488.663010164664, 7520.135184453858, 7648.0, 7672.846543069219, 7754.495957762728, 6216.502679785516, 7411.62941852916, 7430.034095567542, 7475.0656167979005, 7538.849223015619, 7053.788386348418, 7111.7155313788935, 7144.607046612487, 7154.034205382207, 7185.281471852982, 4763.095011947719, 4782.617390608673, 4906.36217291764, 4929.112759703288, 5092.8472145835685, 4754.667173297836, 4939.703617782944, 4965.354503914468, 5046.293518907282, 5232.189010703347], "value": 4674.585260815369, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.39744772984653, 271.1323763955343, 277.68898833306685, 316.13694613704587, 327.27345293448536, 4758.073784622458, 5598.86002849929, 5602.887942241158, 5657.575681823864, 5672.865426914618, 4802.687825291362, 5577.8047768328115, 5608.971955140219, 5633.267675202228, 5637.633553818999, 4042.8180731867137, 4129.669626429878, 4240.512341080769, 4248.511421186557, 4253.510846252674, 3162.845561370095, 4202.6007529284825, 4241.978790106045, 4260.936085958713, 4277.572242775713, 3519.5952465466544, 3580.2839432113683, 4184.7907604619795, 4252.9367658085475, 4272.230998420283, 4041.6766658667234, 4116.0, 4148.128892932487, 4263.936040959388, 4289.313709806416, 3630.8547658093644, 3649.799261040642, 3659.3779057560278, 3688.0779805048737, 3697.482352470656, 4838.854834354975, 5082.872928176788, 5195.506426889439, 5369.785208591652, 5391.595630327741, 4503.774811259424, 4528.660350473695, 4556.407667003302, 4804.375431193957, 4956.231784073474, 5059.367579052619, 5633.126865335838, 5704.0, 5849.824505264849, 5879.382664820175, 3613.692836108934, 3694.1001313968404, 4005.439238506611, 4019.8392064317686, 4027.8791636250958, 5520.475554822286, 5715.942840571583, 6099.0, 6219.906701399483, 6273.780417685405, 4021.3565829467143, 4041.191761647682, 4054.5742697016976, 4068.6745060395097, 4078.3270760324212, 3495.545579074704, 3655.5065066216252, 3848.499695039627, 4142.668586513072, 4178.247915375262, 4074.9812546863286, 4402.119576084796, 4522.163399771053, 4773.498782627774, 4814.855554333376, 4666.556677115668, 5374.596905232123, 5951.583389162717, 6028.457438830526, 6033.698315084228, 1288.7293668329569, 1322.927239001864, 1382.979255311171, 1412.9152250864981, 1413.9929300353433, 778.6573907480804, 848.3043903998662, 863.468966585558, 870.7866572689605, 903.70629545398, 4581.450225972904, 5002.824901128516, 5011.574016208663, 5455.290812194389, 5462.426445223274, 5720.685362305113, 5991.55063370249, 5997.8004399120355, 6042.516598672096, 6088.6042407243385, 4186.016286172808, 4628.356933288701, 4717.669763116617, 4764.618830493552, 5343.65266257692, 2721.8502982335767, 2821.4059056633346, 2904.2739315171207, 3162.652108268089, 3164.054106724348, 5877.441643043904, 6280.842978925563, 6302.306746257908, 6344.0, 6372.776952806629, 5305.814296499604, 5941.643501389931, 6256.968715156486, 6422.293547709748, 6522.380373864476, 5734.913976290359, 6089.782043591211, 6282.167877594664, 6501.934980650228, 6546.705398256994, 5811.767529298781, 6046.455818976313, 6121.632702037892, 6260.060990851409, 6261.4364707174755, 4855.81943826794, 5213.687178769286, 5895.29256489224, 6217.502599791916, 6234.844128896814, 5843.503302219189, 5969.253843269591, 6020.020090055451, 6481.254655714653, 6520.804375868732, 4022.4368588398233, 4045.615666511677, 4051.5616955980204, 4066.072427851942, 4079.449274347925, 6189.752409903734, 6203.689815509116, 6206.875862482815, 6227.283862355705, 6240.032794916795, 5708.601392658825, 5950.910736339045, 5987.832372687284, 5990.580659353804, 6071.539222643051, 5092.541671249605, 5418.864528386822, 5769.769209231584, 5958.106284057426, 6021.578489505692, 4779.761011949458, 5617.7191140442, 5796.82609521715, 5829.0, 5866.560007999332, 4476.373307736984, 5270.917987395034, 5812.18629391877, 5874.647521148745, 6001.5798894077, 5412.377576578744, 5594.664320140804, 5822.301323840997, 5918.23063001807, 5982.581219314607], "value": 4248.578142703469, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.6432194454023, 692.7679227458801, 750.9324160825524, 768.9000429944107, 784.4195295481345, 14812.111273323595, 15146.24268786559, 15222.0, 15571.532854014384, 15821.841781582181, 14214.076085054465, 14387.280635968185, 14593.708125837504, 14620.415183392679, 14678.192699401528, 738.7488253993641, 847.3517758914436, 904.2314033071885, 924.5700749151644, 938.6714649872524, 935.349931797401, 939.3612343606345, 939.9248060155205, 940.5861420974784, 940.92472602192, 976.8241716491028, 979.7501637082534, 986.4771671014374, 988.6292640259902, 988.9653862114827, 962.7159987803615, 976.599594166392, 977.203579083045, 980.691082309074, 980.6959842448864, 706.2055187913597, 721.1058287723185, 730.0327066636728, 730.8501757139774, 732.0848938826467, 14895.702085958328, 14948.40206391743, 15179.392824287015, 15199.164045977466, 15256.542303730906, 12732.0, 12846.293453860035, 12877.291748953803, 12879.549215777497, 12979.545715899898, 10156.441395723232, 10254.48727563619, 10292.536835842406, 10379.273450858444, 10476.947615261875, 3514.3147086318177, 3541.769784963969, 3555.520004799345, 3601.693856022241, 3649.5255616769914, 83205.16794832035, 84021.0, 84436.0, 86240.56879715624, 86242.99999999937, 8443.831123377498, 9616.894057183374, 9633.470159141174, 9636.951815240878, 9933.850992235122, 22592.435189120402, 22629.88685056597, 22646.547069058528, 22683.773162268495, 22757.886210568842, 7404.867333601689, 7467.290607392289, 7504.887426688604, 7533.8116547086765, 7831.2560306770765, 4003.959960400359, 4038.697097717683, 4054.4526488923616, 4060.451839001756, 4237.491501019897, 3597.37046016946, 3678.3562876496508, 3679.7608155469807, 3706.0, 3713.350163721338, 623.7255607532671, 627.4823270801606, 628.2115944489636, 649.5096202367276, 651.1697585578331, 549.3957643680462, 552.2379116818845, 572.2389222334285, 595.8986972214648, 609.1654433426235, 6880.827979300558, 7722.073351197892, 8068.951036365235, 8104.432689711663, 8206.056303525052, 499.0991260774265, 524.9028929648027, 531.989220481089, 537.3846945247669, 579.8144593729966, 3860.324443222481, 3934.11482416454, 3979.7612143271494, 4026.9597304026584, 4032.475778148822, 91190.54404727934, 98237.50881245547, 98421.50789246151, 98673.0, 99982.0, 78279.0, 80263.59868200739, 81360.18639813527, 83859.0, 84283.0, 71951.68289902623, 72135.55728885393, 72753.2724672757, 72816.27183728201, 73004.26995730081, 7530.359919406693, 7566.205548417557, 7698.230176982257, 7816.4137689674635, 7835.84328313442, 46318.53681463142, 46647.0, 47115.0, 47442.28836567385, 47604.80987975328, 65741.67129164323, 65868.0, 70060.64969675119, 70109.64945175241, 71354.57290854257, 9750.512474376395, 11626.76746465083, 11803.173777835469, 12109.524116424993, 12251.0, 8146.714864979697, 10160.441175735405, 10545.525451354553, 10731.517081731497, 10867.13062954946, 11711.238769479956, 11748.530058797895, 11943.761124777282, 12021.879781202077, 12083.0, 11212.327260364404, 11683.88316116862, 11837.585684501, 12066.155369124077, 12391.62825115248, 10464.843027354747, 11755.88244117548, 12047.759044818878, 12141.332226727614, 12307.384630768605, 7901.762947111596, 8514.063453020039, 9624.181944534794, 9970.55132519024, 10054.64808731691, 8155.632996515053, 8993.415427997159, 9900.112976354505, 9906.356086854332, 9984.500774961369], "value": 6942.38480586119, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1592.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 115.0, 107.0, 116.0, 116.0, 114.0, 105.0, 115.0, 114.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 153.0, 251.0, 109.0, 166.0, 168.0, 155.0, 227.0, 123.0], "value": 162.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 201.0, 210.0, 225.0, 193.0, 196.0, 196.0, 195.0, 197.0, 236.0], "value": 204.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 116.0, 107.0, 196.0, 50.0, 76.0, 87.0, 96.0, 129.0, 58.0], "value": 99.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 151.0, 64.0, 81.0, 98.0, 154.0, 119.0, 84.0, 117.0], "value": 102.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 89.0, 85.0, 88.0, 87.0, 88.0, 86.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 105.0, 153.0, 151.0, 95.0, 95.0, 103.0, 96.0, 94.0, 94.0], "value": 111.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 93.0, 97.0, 94.0, 92.0, 92.0, 93.0, 95.0, 91.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 60.0, 59.0, 59.0, 57.0, 58.0, 119.0, 59.0, 58.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 79.0, 99.0, 108.0, 75.0, 75.0, 71.0, 101.0, 71.0, 68.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 89.0, 89.0, 113.0, 82.0, 85.0, 84.0, 85.0, 84.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 127.0, 144.0, 144.0, 148.0, 132.0, 135.0, 147.0, 249.0, 159.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 167.0, 159.0, 161.0, 158.0, 163.0, 164.0, 166.0, 163.0, 164.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 64.0, 67.0, 66.0, 64.0, 67.0, 64.0, 73.0], "value": 66.3, "unit": "score"}]}]} 05:51:52 INFO - Return code: 0 05:51:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:51:52 ERROR - # TBPL SUCCESS # 05:51:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:51:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:51:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:51:52 INFO - 2016-05-17 05:51:52.115 screenresolution[1681:10582] starting screenresolution argv=screenresolution get 05:51:52 INFO - 2016-05-17 05:51:52.117 screenresolution[1681:10582] Display 0: 1600x1200x32@60 05:51:52 INFO - 2016-05-17 05:51:52.123 screenresolution[1682:10585] starting screenresolution argv=screenresolution list 05:51:52 INFO - Available Modes on Display 0 05:51:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:51:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:51:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:51:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:51:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:51:52 INFO - Intel Iris: 05:51:52 INFO - Chipset Model: Intel Iris 05:51:52 INFO - Type: GPU 05:51:52 INFO - Bus: Built-In 05:51:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:51:52 INFO - Vendor: Intel (0x8086) 05:51:52 INFO - Device ID: 0x0a2e 05:51:52 INFO - Revision ID: 0x0009 05:51:52 INFO - Displays: 05:51:52 INFO - Display: 05:51:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:51:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:51:52 INFO - Display Serial Number: 1600x1200 60 05:51:52 INFO - Main Display: Yes 05:51:52 INFO - Mirror: Off 05:51:52 INFO - Online: Yes 05:51:52 INFO - Rotation: Supported 05:51:52 INFO - Return code: 0 05:51:52 INFO - Running post-action listener: _resource_record_post_action 05:51:52 INFO - ##### 05:51:52 INFO - ##### Finished run-tests step (success) 05:51:52 INFO - ##### 05:51:52 INFO - Running post-run listener: _resource_record_post_run 05:51:52 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 36767232; Write bytes: 697913856; Read time: 241; Write time: 1943 05:51:52 INFO - TinderboxPrint: CPU usage
54.6% 05:51:52 INFO - TinderboxPrint: I/O read bytes / time
36,767,232 / 241 05:51:52 INFO - TinderboxPrint: I/O write bytes / time
697,913,856 / 1,943 05:51:52 INFO - TinderboxPrint: CPU idle
2,194.8 (45.4%) 05:51:52 INFO - TinderboxPrint: CPU system
977.0 (20.2%) 05:51:52 INFO - TinderboxPrint: CPU user
1,661.9 (34.4%) 05:51:52 INFO - TinderboxPrint: Swap in / out
431,902,720 / 0 05:51:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 386802176; Write bytes: 381982720; Read time: 28785; Write time: 804 05:51:52 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 34977280; Write bytes: 298617344; Read time: 226; Write time: 1092 05:51:52 INFO - Running post-run listener: _upload_blobber_files 05:51:52 INFO - Blob upload gear active. 05:51:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:51:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:51:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:51:53 INFO - (blobuploader) - INFO - Open directory for files ... 05:51:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:51:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:51:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:54 INFO - (blobuploader) - INFO - Done attempting. 05:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:51:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:55 INFO - (blobuploader) - INFO - Done attempting. 05:51:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:51:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:51:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:58 INFO - (blobuploader) - INFO - Done attempting. 05:51:58 INFO - (blobuploader) - INFO - Iteration through files over. 05:51:58 INFO - Return code: 0 05:51:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:51:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:51:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/119e1545988bc13e915d9b6a1dfcbf0e67dd37a08ac2d42eb60daba2726d50ee73cd01ef4807869f77633c0cc599078a4eb41d05d3de24485b5d562af7f32040", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/289e45c6ca5c7b3edbf1f49fb8ddcac4decb0a9231d87becda36bd32e108f63a14fd970f8d3edafa6ed36c180949f7ad52f2af5f08f7a382c32e2177137b1343", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ea48ced5a6dfb2b8fb6dda37b1dfde495f188097e108276751a3e01a3d883ffb2f3fe62da51ff22d79ad6d1095584df1adad6b16ffa58dc99990173a38580ac"} 05:51:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:51:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:51:58 INFO - Contents: 05:51:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/119e1545988bc13e915d9b6a1dfcbf0e67dd37a08ac2d42eb60daba2726d50ee73cd01ef4807869f77633c0cc599078a4eb41d05d3de24485b5d562af7f32040", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/289e45c6ca5c7b3edbf1f49fb8ddcac4decb0a9231d87becda36bd32e108f63a14fd970f8d3edafa6ed36c180949f7ad52f2af5f08f7a382c32e2177137b1343", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ea48ced5a6dfb2b8fb6dda37b1dfde495f188097e108276751a3e01a3d883ffb2f3fe62da51ff22d79ad6d1095584df1adad6b16ffa58dc99990173a38580ac"} 05:51:58 INFO - Running post-run listener: copy_logs_to_upload_dir 05:51:58 INFO - Copying logs to upload dir... 05:51:58 INFO - mkdir: /builds/slave/test/build/upload/logs 05:51:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.537635 ========= master_lag: 1.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-17 05:52:00.194244) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 05:52:00.198750) ========= 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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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/119e1545988bc13e915d9b6a1dfcbf0e67dd37a08ac2d42eb60daba2726d50ee73cd01ef4807869f77633c0cc599078a4eb41d05d3de24485b5d562af7f32040", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/289e45c6ca5c7b3edbf1f49fb8ddcac4decb0a9231d87becda36bd32e108f63a14fd970f8d3edafa6ed36c180949f7ad52f2af5f08f7a382c32e2177137b1343", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ea48ced5a6dfb2b8fb6dda37b1dfde495f188097e108276751a3e01a3d883ffb2f3fe62da51ff22d79ad6d1095584df1adad6b16ffa58dc99990173a38580ac"} build_url:https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011204 build_url: 'https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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/119e1545988bc13e915d9b6a1dfcbf0e67dd37a08ac2d42eb60daba2726d50ee73cd01ef4807869f77633c0cc599078a4eb41d05d3de24485b5d562af7f32040", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/289e45c6ca5c7b3edbf1f49fb8ddcac4decb0a9231d87becda36bd32e108f63a14fd970f8d3edafa6ed36c180949f7ad52f2af5f08f7a382c32e2177137b1343", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ea48ced5a6dfb2b8fb6dda37b1dfde495f188097e108276751a3e01a3d883ffb2f3fe62da51ff22d79ad6d1095584df1adad6b16ffa58dc99990173a38580ac"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 05:52:00.236417) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:52:00.236833) ========= 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.lL1GwUxlEX/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.WRLQlsJmyt/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.005137 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:52:00.289255) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 05:52:00.289747) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 05:52:00.506793) ========= ========= Total master_lag: 3.41 =========