builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0080 starttime: 1464182296.99 results: success (0) buildid: 20160525045846 builduid: 6759c13f5aa24c259bcb8d8cfe2dca81 revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:16.985791) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:16.986703) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:16.987222) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:17.013456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:17.014098) ========= 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.viOknWGlTC/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.zmEmJDqYUj/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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-25 06:18:17-- 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% 8.95M=0.001s 2016-05-25 06:18:17 (8.95 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086529 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:17.324147) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:17.324546) ========= 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.viOknWGlTC/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.zmEmJDqYUj/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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.028128 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:17.370572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 06:18:17.370918) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 242c458c4c61e94c8d12628612ef5e4e83992eaa --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 242c458c4c61e94c8d12628612ef5e4e83992eaa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.viOknWGlTC/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.zmEmJDqYUj/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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-25 06:18:17,443 truncating revision to first 12 chars 2016-05-25 06:18:17,443 Setting DEBUG logging. 2016-05-25 06:18:17,443 attempt 1/10 2016-05-25 06:18:17,443 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/242c458c4c61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 06:18:18,488 unpacking tar archive at: mozilla-central-242c458c4c61/testing/mozharness/ program finished with exit code 0 elapsedTime=1.293504 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 06:18:18.684783) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:18.685196) ========= script_repo_revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:18.685652) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:18.685996) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:18.702457) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 47 secs) (at 2016-05-25 06:18:18.702860) ========= /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.viOknWGlTC/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.zmEmJDqYUj/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:18:18 INFO - MultiFileLogger online at 20160525 06:18:18 in /builds/slave/test 06:18:18 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 06:18:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:18:18 INFO - {'append_to_log': False, 06:18:18 INFO - 'base_work_dir': '/builds/slave/test', 06:18:18 INFO - 'blob_upload_branch': 'Firefox', 06:18:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:18:18 INFO - 'branch': 'Firefox', 06:18:18 INFO - 'buildbot_json_path': 'buildprops.json', 06:18:18 INFO - 'config_files': ('talos/mac_config.py',), 06:18:18 INFO - 'default_actions': ('clobber', 06:18:18 INFO - 'read-buildbot-config', 06:18:18 INFO - 'download-and-extract', 06:18:18 INFO - 'populate-webroot', 06:18:18 INFO - 'create-virtualenv', 06:18:18 INFO - 'install', 06:18:18 INFO - 'run-tests'), 06:18:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:18:18 INFO - 'download_minidump_stackwalk': True, 06:18:18 INFO - 'download_symbols': 'ondemand', 06:18:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:18:18 INFO - 'tooltool.py': '/tools/tooltool.py', 06:18:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:18:18 INFO - '/tools/misc-python/virtualenv.py')}, 06:18:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:18:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:18:18 INFO - 'installer_path': 'installer.exe', 06:18:18 INFO - 'log_level': 'info', 06:18:18 INFO - 'log_name': 'talos', 06:18:18 INFO - 'log_to_console': True, 06:18:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:18:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:18:18 INFO - 'opt_config_files': (), 06:18:18 INFO - 'pip_index': False, 06:18:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:18:18 INFO - 'cmd': ('bash', 06:18:18 INFO - '-c', 06:18:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:18:18 INFO - 'enabled': True, 06:18:18 INFO - 'halt_on_failure': False, 06:18:18 INFO - 'name': 'check_screen_resolution'},), 06:18:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:18:18 INFO - 'cmd': ('bash', 06:18:18 INFO - '-c', 06:18:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:18:18 INFO - 'enabled': True, 06:18:18 INFO - 'halt_on_failure': False, 06:18:18 INFO - 'name': 'check_screen_resolution'},), 06:18:18 INFO - 'run_cmd_checks_enabled': True, 06:18:18 INFO - 'sps_profile': False, 06:18:18 INFO - 'sps_profile_interval': 0, 06:18:18 INFO - 'suite': 'dromaeojs-e10s', 06:18:18 INFO - 'system_bits': '32', 06:18:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:18:18 INFO - 'title': 't-yosemite-r7-0080', 06:18:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:18:18 INFO - 'use_talos_json': True, 06:18:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:18:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:18:18 INFO - 'work_dir': 'build'} 06:18:18 INFO - ##### 06:18:18 INFO - ##### Running clobber step. 06:18:18 INFO - ##### 06:18:18 INFO - Running pre-action listener: _resource_record_pre_action 06:18:18 INFO - Running main action method: clobber 06:18:18 INFO - rmtree: /builds/slave/test/build 06:18:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:18:20 INFO - Running post-action listener: _resource_record_post_action 06:18:20 INFO - ##### 06:18:20 INFO - ##### Finished clobber step (success) 06:18:20 INFO - ##### 06:18:20 INFO - ##### 06:18:20 INFO - ##### Running read-buildbot-config step. 06:18:20 INFO - ##### 06:18:20 INFO - Running pre-action listener: _resource_record_pre_action 06:18:20 INFO - Running main action method: read_buildbot_config 06:18:20 INFO - Using buildbot properties: 06:18:20 INFO - { 06:18:20 INFO - "project": "", 06:18:20 INFO - "product": "firefox", 06:18:20 INFO - "script_repo_revision": "production", 06:18:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:18:20 INFO - "repository": "", 06:18:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:18:20 INFO - "buildid": "20160525045846", 06:18:20 INFO - "pgo_build": "False", 06:18:20 INFO - "basedir": "/builds/slave/test", 06:18:20 INFO - "buildnumber": 9, 06:18:20 INFO - "slavename": "t-yosemite-r7-0080", 06:18:20 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:18:20 INFO - "platform": "yosemite_r7", 06:18:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:18:20 INFO - "branch": "mozilla-central", 06:18:20 INFO - "repo_path": "mozilla-central", 06:18:20 INFO - "revision": "242c458c4c61e94c8d12628612ef5e4e83992eaa", 06:18:20 INFO - "stage_platform": "macosx64", 06:18:20 INFO - "builduid": "6759c13f5aa24c259bcb8d8cfe2dca81", 06:18:20 INFO - "slavebuilddir": "test" 06:18:20 INFO - } 06:18:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 06:18:20 INFO - Running post-action listener: _resource_record_post_action 06:18:20 INFO - ##### 06:18:20 INFO - ##### Finished read-buildbot-config step (success) 06:18:20 INFO - ##### 06:18:20 INFO - ##### 06:18:20 INFO - ##### Running download-and-extract step. 06:18:20 INFO - ##### 06:18:20 INFO - Running pre-action listener: _resource_record_pre_action 06:18:20 INFO - Running main action method: download_and_extract 06:18:20 INFO - mkdir: /builds/slave/test/build/tests 06:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:20 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:18:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:18:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:18:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:22 INFO - Downloaded 1199 bytes. 06:18:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:18:22 INFO - Using the following test package requirements: 06:18:22 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:18:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:22 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 06:18:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:22 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 06:18:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:22 INFO - u'jsshell-mac.zip'], 06:18:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:22 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 06:18:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:18:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:22 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 06:18:22 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:22 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 06:18:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:22 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 06:18:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:22 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 06:18:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:22 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:18:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:28 INFO - Downloaded 18632937 bytes. 06:18:28 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'] 06:18:28 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 06:18:31 INFO - Return code: 0 06:18:31 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 06:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:31 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:18:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:40 INFO - Downloaded 18632937 bytes. 06:18:40 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'] 06:18:40 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 06:18:44 INFO - Return code: 0 06:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:44 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:18:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:18:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:18:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:45 INFO - Downloaded 10840359 bytes. 06:18:45 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'] 06:18:45 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 06:18:45 INFO - Return code: 0 06:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:45 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:18:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:18:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:18:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 06:18:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/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 06:18:49 INFO - Downloaded 110240681 bytes. 06:18:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:18:49 INFO - mkdir: /builds/slave/test/properties 06:18:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:18:49 INFO - Writing to file /builds/slave/test/properties/build_url 06:18:49 INFO - Contents: 06:18:49 INFO - build_url:https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:18:50 INFO - Running post-action listener: _resource_record_post_action 06:18:50 INFO - Running post-action listener: set_extra_try_arguments 06:18:50 INFO - ##### 06:18:50 INFO - ##### Finished download-and-extract step (success) 06:18:50 INFO - ##### 06:18:50 INFO - ##### 06:18:50 INFO - ##### Running populate-webroot step. 06:18:50 INFO - ##### 06:18:50 INFO - Running pre-action listener: _resource_record_pre_action 06:18:50 INFO - Running main action method: populate_webroot 06:18:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:18:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:18:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:18:50 INFO - '--tppagecycles', 06:18:50 INFO - '7'], 06:18:50 INFO - 'tests': ['tsvgm']}}, 06:18:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:18:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:18:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:18:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:18:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:50 INFO - 'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:18:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:18:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:50 INFO - 'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['damp', 'tps']}, 06:18:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:50 INFO - 'tests': ['damp', 'tps']}, 06:18:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['dromaeo_dom']}, 06:18:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:18:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['a11yr', 06:18:50 INFO - 'ts_paint', 06:18:50 INFO - 'tpaint', 06:18:50 INFO - 'sessionrestore', 06:18:50 INFO - 'sessionrestore_no_auto_restore', 06:18:50 INFO - 'tabpaint']}, 06:18:50 INFO - 'other-e10s': {'tests': ['a11yr', 06:18:50 INFO - 'ts_paint', 06:18:50 INFO - 'tpaint', 06:18:50 INFO - 'sessionrestore', 06:18:50 INFO - 'sessionrestore_no_auto_restore', 06:18:50 INFO - 'tabpaint']}, 06:18:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:18:50 INFO - 'ts_paint', 06:18:50 INFO - 'tpaint', 06:18:50 INFO - 'sessionrestore', 06:18:50 INFO - 'sessionrestore_no_auto_restore', 06:18:50 INFO - 'tabpaint']}, 06:18:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:18:50 INFO - 'ts_paint', 06:18:50 INFO - 'tpaint', 06:18:50 INFO - 'sessionrestore', 06:18:50 INFO - 'sessionrestore_no_auto_restore', 06:18:50 INFO - 'tabpaint']}, 06:18:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['a11yr', 06:18:50 INFO - 'ts_paint', 06:18:50 INFO - 'tpaint', 06:18:50 INFO - 'sessionrestore', 06:18:50 INFO - 'sessionrestore_no_auto_restore', 06:18:50 INFO - 'tabpaint']}, 06:18:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['a11yr', 06:18:50 INFO - 'ts_paint', 06:18:50 INFO - 'tpaint', 06:18:50 INFO - 'sessionrestore', 06:18:50 INFO - 'sessionrestore_no_auto_restore', 06:18:50 INFO - 'tabpaint']}, 06:18:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['tsvgx', 06:18:50 INFO - 'tsvgr_opacity', 06:18:50 INFO - 'tart', 06:18:50 INFO - 'tscrollx', 06:18:50 INFO - 'cart']}, 06:18:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:18:50 INFO - 'tsvgr_opacity', 06:18:50 INFO - 'tart', 06:18:50 INFO - 'tscrollx', 06:18:50 INFO - 'cart']}, 06:18:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:50 INFO - 'talos_options': ['--disable-e10s'], 06:18:50 INFO - 'tests': ['tp5o']}, 06:18:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:50 INFO - 'tests': ['tp5o']}, 06:18:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:50 INFO - 'talos_options': ['--disable-e10s', 06:18:50 INFO - '--xperf_path', 06:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:18:50 INFO - 'tests': ['tp5n']}, 06:18:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:50 INFO - 'talos_options': ['--xperf_path', 06:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:18:50 INFO - 'tests': ['tp5n']}}, 06:18:50 INFO - 'talos.zip': {'path': '', 06:18:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:18:50 INFO - Running post-action listener: _resource_record_post_action 06:18:50 INFO - ##### 06:18:50 INFO - ##### Finished populate-webroot step (success) 06:18:50 INFO - ##### 06:18:50 INFO - ##### 06:18:50 INFO - ##### Running create-virtualenv step. 06:18:50 INFO - ##### 06:18:50 INFO - Running pre-action listener: _resource_record_pre_action 06:18:50 INFO - Running main action method: create_virtualenv 06:18:50 INFO - Creating virtualenv /builds/slave/test/build/venv 06:18:50 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 06:18:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:18:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:18:50 INFO - Using real prefix '/tools/python27' 06:18:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:18:51 INFO - Installing distribute.............................................................................................................................................................................................done. 06:18:54 INFO - Installing pip.................done. 06:18:54 INFO - Return code: 0 06:18:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:18:54 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')]} 06:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:54 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')]} 06:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:54 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:18:54 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 06:18:54 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 06:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:54 INFO - 'HOME': '/Users/cltbld', 06:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:54 INFO - 'LOGNAME': 'cltbld', 06:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:54 INFO - 'MOZ_NO_REMOTE': '1', 06:18:54 INFO - 'NO_EM_RESTART': '1', 06:18:54 INFO - 'PAGER': '/bin/cat', 06:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:54 INFO - 'PWD': '/builds/slave/test', 06:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:54 INFO - 'SHELL': '/bin/bash', 06:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:18:54 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:54 INFO - 'USER': 'cltbld', 06:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:54 INFO - 'XPC_FLAGS': '0x0', 06:18:54 INFO - 'XPC_SERVICE_NAME': '0', 06:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:54 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 06:18:54 INFO - Cleaning up... 06:18:54 INFO - Return code: 0 06:18:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:18:54 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')]} 06:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:54 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')]} 06:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:54 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:18:54 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 06:18:54 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 06:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:54 INFO - 'HOME': '/Users/cltbld', 06:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:54 INFO - 'LOGNAME': 'cltbld', 06:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:54 INFO - 'MOZ_NO_REMOTE': '1', 06:18:54 INFO - 'NO_EM_RESTART': '1', 06:18:54 INFO - 'PAGER': '/bin/cat', 06:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:54 INFO - 'PWD': '/builds/slave/test', 06:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:54 INFO - 'SHELL': '/bin/bash', 06:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:18:54 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:54 INFO - 'USER': 'cltbld', 06:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:54 INFO - 'XPC_FLAGS': '0x0', 06:18:54 INFO - 'XPC_SERVICE_NAME': '0', 06:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:54 INFO - Downloading/unpacking psutil>=3.1.1 06:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:18:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:18:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:18:57 INFO - Installing collected packages: psutil 06:18:57 INFO - Running setup.py install for psutil 06:18:58 INFO - building 'psutil._psutil_osx' extension 06:18:58 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 06:18:58 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 06:18:58 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 06:18:58 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 06:18:58 INFO - building 'psutil._psutil_posix' extension 06:18:58 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 06:18:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:18:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:18:58 INFO - ^ 06:18:58 INFO - 1 warning generated. 06:18:58 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 06:18:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:18:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:18:59 INFO - Successfully installed psutil 06:18:59 INFO - Cleaning up... 06:18:59 INFO - Return code: 0 06:18:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:18:59 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')]} 06:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:59 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')]} 06:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:59 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:18:59 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 06:18:59 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 06:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:59 INFO - 'HOME': '/Users/cltbld', 06:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:59 INFO - 'LOGNAME': 'cltbld', 06:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:59 INFO - 'MOZ_NO_REMOTE': '1', 06:18:59 INFO - 'NO_EM_RESTART': '1', 06:18:59 INFO - 'PAGER': '/bin/cat', 06:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:59 INFO - 'PWD': '/builds/slave/test', 06:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:59 INFO - 'SHELL': '/bin/bash', 06:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:18:59 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:59 INFO - 'USER': 'cltbld', 06:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:59 INFO - 'XPC_FLAGS': '0x0', 06:18:59 INFO - 'XPC_SERVICE_NAME': '0', 06:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:19:01 INFO - Installing collected packages: mozsystemmonitor 06:19:01 INFO - Running setup.py install for mozsystemmonitor 06:19:02 INFO - Successfully installed mozsystemmonitor 06:19:02 INFO - Cleaning up... 06:19:02 INFO - Return code: 0 06:19:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:02 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:19:02 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 06:19:02 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 06:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:02 INFO - 'HOME': '/Users/cltbld', 06:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:02 INFO - 'LOGNAME': 'cltbld', 06:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:02 INFO - 'MOZ_NO_REMOTE': '1', 06:19:02 INFO - 'NO_EM_RESTART': '1', 06:19:02 INFO - 'PAGER': '/bin/cat', 06:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:02 INFO - 'PWD': '/builds/slave/test', 06:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:02 INFO - 'SHELL': '/bin/bash', 06:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:19:02 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:02 INFO - 'USER': 'cltbld', 06:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:02 INFO - 'XPC_FLAGS': '0x0', 06:19:02 INFO - 'XPC_SERVICE_NAME': '0', 06:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:02 INFO - Downloading/unpacking blobuploader==1.2.4 06:19:02 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 06:19:02 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 06:19:02 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 06:19:02 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 06:19:02 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 06:19:02 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 06:19:04 INFO - Downloading blobuploader-1.2.4.tar.gz 06:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:19:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:19:04 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 06:19:04 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 06:19:04 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 06:19:04 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 06:19:04 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 06:19:04 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 06:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:19:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:05 INFO - Downloading docopt-0.6.1.tar.gz 06:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:19:06 INFO - Installing collected packages: blobuploader, requests, docopt 06:19:06 INFO - Running setup.py install for blobuploader 06:19:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:19:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:19:06 INFO - Running setup.py install for requests 06:19:06 INFO - Running setup.py install for docopt 06:19:06 INFO - Successfully installed blobuploader requests docopt 06:19:06 INFO - Cleaning up... 06:19:06 INFO - Return code: 0 06:19:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:06 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:19:06 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 06:19:06 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 06:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:06 INFO - 'HOME': '/Users/cltbld', 06:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:06 INFO - 'LOGNAME': 'cltbld', 06:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:06 INFO - 'MOZ_NO_REMOTE': '1', 06:19:06 INFO - 'NO_EM_RESTART': '1', 06:19:06 INFO - 'PAGER': '/bin/cat', 06:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:06 INFO - 'PWD': '/builds/slave/test', 06:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:06 INFO - 'SHELL': '/bin/bash', 06:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:19:06 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:06 INFO - 'USER': 'cltbld', 06:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:06 INFO - 'XPC_FLAGS': '0x0', 06:19:06 INFO - 'XPC_SERVICE_NAME': '0', 06:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-tjjk_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-vQclxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-SomdR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-G2Isqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-YUnxwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-2xP1dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-SGjD7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-5gkFWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:07 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-p2nsZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:08 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Fp5Pe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:08 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-H39BLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:08 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-EtQ5T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:08 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-XL5nrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:08 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-abZLPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:08 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-cvc7so-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:08 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-m3uXRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:08 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-DhOIDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:19:08 INFO - Running setup.py install for manifestparser 06:19:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:19:09 INFO - Running setup.py install for mozcrash 06:19:09 INFO - Running setup.py install for mozdebug 06:19:09 INFO - Running setup.py install for mozdevice 06:19:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:19:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:19:09 INFO - Running setup.py install for mozfile 06:19:09 INFO - Running setup.py install for mozhttpd 06:19:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:19:09 INFO - Running setup.py install for mozinfo 06:19:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:19:09 INFO - Running setup.py install for mozInstall 06:19:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Running setup.py install for mozleak 06:19:10 INFO - Running setup.py install for mozlog 06:19:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Running setup.py install for moznetwork 06:19:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Running setup.py install for mozprocess 06:19:10 INFO - Running setup.py install for mozprofile 06:19:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:19:10 INFO - Running setup.py install for mozrunner 06:19:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:19:11 INFO - Running setup.py install for mozscreenshot 06:19:11 INFO - Running setup.py install for moztest 06:19:11 INFO - Running setup.py install for mozversion 06:19:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:19:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:19:11 INFO - Cleaning up... 06:19:11 INFO - Return code: 0 06:19:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:11 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:19:11 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 06:19:11 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 06:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:11 INFO - 'HOME': '/Users/cltbld', 06:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:11 INFO - 'LOGNAME': 'cltbld', 06:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:11 INFO - 'MOZ_NO_REMOTE': '1', 06:19:11 INFO - 'NO_EM_RESTART': '1', 06:19:11 INFO - 'PAGER': '/bin/cat', 06:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:11 INFO - 'PWD': '/builds/slave/test', 06:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:11 INFO - 'SHELL': '/bin/bash', 06:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:19:11 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:11 INFO - 'USER': 'cltbld', 06:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:11 INFO - 'XPC_FLAGS': '0x0', 06:19:11 INFO - 'XPC_SERVICE_NAME': '0', 06:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-G6W6PT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:11 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)) 06:19:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-ofeF8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:11 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)) 06:19:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:11 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-LNN_Ua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-OvZq1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-QCRpRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-R5gSTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-PEJsLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-sOiwFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-9eDDpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-wJCXB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-zYU7cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:12 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)) 06:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:12 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-3BYNQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:13 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)) 06:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:13 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-OMMJc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:13 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)) 06:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:13 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-mNfSES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:13 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)) 06:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:13 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-sVjeTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:13 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)) 06:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:13 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-WjHXtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:13 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)) 06:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:13 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-ImKGtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:13 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)) 06:19:13 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)) 06:19:13 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)) 06:19:13 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)) 06:19:13 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)) 06:19:13 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)) 06:19:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:16 INFO - Downloading blessings-1.6.tar.gz 06:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:19:16 INFO - Installing collected packages: blessings 06:19:16 INFO - Running setup.py install for blessings 06:19:16 INFO - Successfully installed blessings 06:19:16 INFO - Cleaning up... 06:19:16 INFO - Return code: 0 06:19:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:19:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:16 INFO - Reading from file tmpfile_stdout 06:19:16 INFO - Current package versions: 06:19:16 INFO - blessings == 1.6 06:19:16 INFO - blobuploader == 1.2.4 06:19:16 INFO - docopt == 0.6.1 06:19:16 INFO - manifestparser == 1.1 06:19:16 INFO - mozInstall == 1.12 06:19:16 INFO - mozcrash == 0.17 06:19:16 INFO - mozdebug == 0.1 06:19:16 INFO - mozdevice == 0.48 06:19:16 INFO - mozfile == 1.2 06:19:16 INFO - mozhttpd == 0.7 06:19:16 INFO - mozinfo == 0.9 06:19:16 INFO - mozleak == 0.1 06:19:16 INFO - mozlog == 3.2 06:19:16 INFO - moznetwork == 0.27 06:19:16 INFO - mozprocess == 0.22 06:19:16 INFO - mozprofile == 0.28 06:19:16 INFO - mozrunner == 6.11 06:19:16 INFO - mozscreenshot == 0.1 06:19:16 INFO - mozsystemmonitor == 0.3 06:19:16 INFO - moztest == 0.7 06:19:16 INFO - mozversion == 1.4 06:19:16 INFO - psutil == 3.1.1 06:19:16 INFO - requests == 1.2.3 06:19:16 INFO - wsgiref == 0.1.2 06:19:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:17 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')]} 06:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:17 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:19:17 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 06:19:17 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 06:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:17 INFO - 'HOME': '/Users/cltbld', 06:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:17 INFO - 'LOGNAME': 'cltbld', 06:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:17 INFO - 'MOZ_NO_REMOTE': '1', 06:19:17 INFO - 'NO_EM_RESTART': '1', 06:19:17 INFO - 'PAGER': '/bin/cat', 06:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:17 INFO - 'PWD': '/builds/slave/test', 06:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:17 INFO - 'SHELL': '/bin/bash', 06:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:19:17 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:17 INFO - 'USER': 'cltbld', 06:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:17 INFO - 'XPC_FLAGS': '0x0', 06:19:17 INFO - 'XPC_SERVICE_NAME': '0', 06:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 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)) 06:19:17 INFO - Cleaning up... 06:19:17 INFO - Return code: 0 06:19:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:19:17 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')]} 06:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:17 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')]} 06:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:17 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:19:17 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 06:19:17 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 06:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:17 INFO - 'HOME': '/Users/cltbld', 06:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:17 INFO - 'LOGNAME': 'cltbld', 06:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:17 INFO - 'MOZ_NO_REMOTE': '1', 06:19:17 INFO - 'NO_EM_RESTART': '1', 06:19:17 INFO - 'PAGER': '/bin/cat', 06:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:17 INFO - 'PWD': '/builds/slave/test', 06:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:17 INFO - 'SHELL': '/bin/bash', 06:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:19:17 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:17 INFO - 'USER': 'cltbld', 06:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:17 INFO - 'XPC_FLAGS': '0x0', 06:19:17 INFO - 'XPC_SERVICE_NAME': '0', 06:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:17 INFO - Downloading/unpacking jsonschema 06:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:19:20 INFO - Downloading/unpacking functools32 (from jsonschema) 06:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:20 INFO - Downloading functools32-3.2.3-2.tar.gz 06:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:19:20 INFO - warning: no files found matching '*.txt' 06:19:20 INFO - no previously-included directories found matching 'build' 06:19:20 INFO - no previously-included directories found matching 'dist' 06:19:20 INFO - no previously-included directories found matching '.git*' 06:19:20 INFO - Installing collected packages: jsonschema, functools32 06:19:20 INFO - Running setup.py install for functools32 06:19:20 INFO - warning: no files found matching '*.txt' 06:19:20 INFO - no previously-included directories found matching 'build' 06:19:20 INFO - no previously-included directories found matching 'dist' 06:19:20 INFO - no previously-included directories found matching '.git*' 06:19:21 INFO - Successfully installed jsonschema functools32 06:19:21 INFO - Cleaning up... 06:19:21 INFO - Return code: 0 06:19:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:19:21 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')]} 06:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:21 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')]} 06:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:21 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 0x1069fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a01570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc373572ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, '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.viOknWGlTC/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.zmEmJDqYUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 06:19:21 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 06:19:21 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 06:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:21 INFO - 'HOME': '/Users/cltbld', 06:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:21 INFO - 'LOGNAME': 'cltbld', 06:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:21 INFO - 'MOZ_NO_REMOTE': '1', 06:19:21 INFO - 'NO_EM_RESTART': '1', 06:19:21 INFO - 'PAGER': '/bin/cat', 06:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:21 INFO - 'PWD': '/builds/slave/test', 06:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:21 INFO - 'SHELL': '/bin/bash', 06:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:19:21 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:21 INFO - 'USER': 'cltbld', 06:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:21 INFO - 'XPC_FLAGS': '0x0', 06:19:21 INFO - 'XPC_SERVICE_NAME': '0', 06:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:21 INFO - Downloading/unpacking flake8 06:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:19:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:19:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:24 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 06:19:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:19:25 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 06:19:25 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 06:19:25 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 06:19:25 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 06:19:25 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 06:19:25 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 06:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:19:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:19:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:19:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:19:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:19:25 INFO - no previously-included directories found matching 'docs/_build' 06:19:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:19:25 INFO - Running setup.py install for pep8 06:19:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:19:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:19:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:19:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:19:25 INFO - no previously-included directories found matching 'docs/_build' 06:19:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:19:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:19:25 INFO - Cleaning up... 06:19:25 INFO - Return code: 0 06:19:25 INFO - Running post-action listener: _resource_record_post_action 06:19:25 INFO - Running post-action listener: _start_resource_monitoring 06:19:25 INFO - Starting resource monitoring. 06:19:25 INFO - ##### 06:19:25 INFO - ##### Finished create-virtualenv step (success) 06:19:25 INFO - ##### 06:19:25 INFO - ##### 06:19:25 INFO - ##### Running install step. 06:19:25 INFO - ##### 06:19:25 INFO - Running pre-action listener: _resource_record_pre_action 06:19:25 INFO - Running main action method: install 06:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:26 INFO - Reading from file tmpfile_stdout 06:19:26 INFO - Detecting whether we're running mozinstall >=1.0... 06:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:19:26 INFO - Reading from file tmpfile_stdout 06:19:26 INFO - Output received: 06:19:26 INFO - Usage: mozinstall [options] installer 06:19:26 INFO - Options: 06:19:26 INFO - -h, --help show this help message and exit 06:19:26 INFO - -d DEST, --destination=DEST 06:19:26 INFO - Directory to install application into. [default: 06:19:26 INFO - "/builds/slave/test"] 06:19:26 INFO - --app=APP Application being installed. [default: firefox] 06:19:26 INFO - mkdir: /builds/slave/test/build/application 06:19:26 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'] 06:19:26 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 06:20:00 INFO - Reading from file tmpfile_stdout 06:20:00 INFO - Output received: 06:20:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:20:00 INFO - Running post-action listener: _resource_record_post_action 06:20:00 INFO - ##### 06:20:00 INFO - ##### Finished install step (success) 06:20:00 INFO - ##### 06:20:00 INFO - ##### 06:20:00 INFO - ##### Running run-tests step. 06:20:00 INFO - ##### 06:20:00 INFO - Running pre-action listener: _resource_record_pre_action 06:20:00 INFO - Running main action method: run_tests 06:20:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:20:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:20:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:20:00 INFO - 2016-05-25 06:20:00.403 screenresolution[1645:7858] starting screenresolution argv=screenresolution get 06:20:00 INFO - 2016-05-25 06:20:00.405 screenresolution[1645:7858] Display 0: 1600x1200x32@60 06:20:00 INFO - 2016-05-25 06:20:00.411 screenresolution[1646:7860] starting screenresolution argv=screenresolution list 06:20:00 INFO - Available Modes on Display 0 06:20:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:20:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:20:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:20:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:20:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:20:00 INFO - Intel Iris: 06:20:00 INFO - Chipset Model: Intel Iris 06:20:00 INFO - Type: GPU 06:20:00 INFO - Bus: Built-In 06:20:00 INFO - VRAM (Dynamic, Max): 1536 MB 06:20:00 INFO - Vendor: Intel (0x8086) 06:20:00 INFO - Device ID: 0x0a2e 06:20:00 INFO - Revision ID: 0x0009 06:20:00 INFO - Displays: 06:20:00 INFO - Display: 06:20:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:20:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:20:00 INFO - Display Serial Number: 1600x1200 60 06:20:00 INFO - Main Display: Yes 06:20:00 INFO - Mirror: Off 06:20:00 INFO - Online: Yes 06:20:00 INFO - Rotation: Supported 06:20:00 INFO - Return code: 0 06:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:20:00 INFO - Python 2.7.3 06:20:00 INFO - Return code: 0 06:20:00 INFO - grabbing minidump binary from tooltool 06:20:00 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')]} 06:20:00 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 0x10689fd30>, '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 0x1066c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106896b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:20:00 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 06:20:00 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 06:20:00 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 06:20:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:20:00 INFO - Return code: 0 06:20:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:20:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:20:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:20:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:20:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:20:01 INFO - Return code: 0 06:20:01 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/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--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 06:20:01 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/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0080 --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 06:20:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.viOknWGlTC/Render', 06:20:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:01 INFO - 'HOME': '/Users/cltbld', 06:20:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:01 INFO - 'LOGNAME': 'cltbld', 06:20:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:20:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:01 INFO - 'MOZ_NO_REMOTE': '1', 06:20:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:20:01 INFO - 'NO_EM_RESTART': '1', 06:20:01 INFO - 'PAGER': '/bin/cat', 06:20:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:01 INFO - 'PWD': '/builds/slave/test', 06:20:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:20:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:01 INFO - 'SHELL': '/bin/bash', 06:20:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zmEmJDqYUj/Listeners', 06:20:01 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 06:20:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:01 INFO - 'USER': 'cltbld', 06:20:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:01 INFO - 'XPC_FLAGS': '0x0', 06:20:01 INFO - 'XPC_SERVICE_NAME': '0', 06:20:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:01 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/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--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 06:20:02 INFO - mozversion application_buildid: 20160525045846 06:20:02 INFO - mozversion application_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 06:20:02 INFO - mozversion application_display_name: Nightly 06:20:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:20:02 INFO - mozversion application_name: Firefox 06:20:02 INFO - mozversion application_remotingname: firefox 06:20:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:20:02 INFO - mozversion application_vendor: Mozilla 06:20:02 INFO - mozversion application_version: 49.0a1 06:20:02 INFO - mozversion platform_buildid: 20160525045846 06:20:02 INFO - mozversion platform_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 06:20:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:20:02 INFO - mozversion platform_version: 49.0a1 06:20:02 INFO - using testdate: 1464182402 06:20:02 INFO - actual date: 1464182402 06:20:02 INFO - starting webserver on 'localhost:49270' 06:20:02 INFO - SUITE-START | Running 2 tests 06:20:02 INFO - TEST-START | dromaeo_css 06:20:02 INFO - operating with platform_type : mac_ 06:20:02 INFO - Initialising browser for dromaeo_css test... 06:20:02 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpP_ObLu/profile http://localhost:49270/getInfo.html) 06:20:05 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 06:20:05 INFO - PROCESS | 1664 | colorDepth:24 06:20:05 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 06:20:05 INFO - PROCESS | 1664 | __metrics 06:20:05 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 06:20:06 INFO - TEST-INFO | 1664: exit 0 06:20:06 INFO - Browser initialized. 06:20:06 INFO - Running cycle 1/1 for dromaeo_css test... 06:20:06 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpP_ObLu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:20:18 INFO - PROCESS | 1668 | RSS: Main: 249765888 06:20:18 INFO - PROCESS | 1668 | 06:21:07 INFO - PROCESS | 1668 | 1464182467808 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] 06:23:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 06:23:20 INFO - PROCESS | 1668 | RSS: Main: 262529024 06:23:20 INFO - PROCESS | 1668 | 06:26:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 06:26:23 INFO - PROCESS | 1668 | RSS: Main: 273694720 06:26:23 INFO - PROCESS | 1668 | 06:29:25 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 06:29:26 INFO - PROCESS | 1668 | RSS: Main: 275566592 06:29:26 INFO - PROCESS | 1668 | 06:32:28 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 06:32:28 INFO - PROCESS | 1668 | RSS: Main: 277344256 06:32:28 INFO - PROCESS | 1668 | 06:35:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 06:35:32 INFO - PROCESS | 1668 | RSS: Main: 280317952 06:35:32 INFO - PROCESS | 1668 | 06:38:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 06:38:35 INFO - PROCESS | 1668 | RSS: Main: 281120768 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | __start_tp_report 06:38:35 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 06:38:35 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 06:38:35 INFO - PROCESS | 1668 | |i|pagename|runs| 06:38:35 INFO - PROCESS | 1668 | |0;dojo.html;3471.1322169457635;3498.8425520851565;3528.1708798432364;3543.061088811465;3550.3964326064565;4738.8104475820965;5811.6513009219425;5875.823725288237;5879.11813228016;5902.114682797583;5590.8043218487255;5695.117256825189;5846.649201047941;5850.502707269877;5895.292564892219;5244.449332820056;5278.551323137519;5300.761465734032;5371.650842695231;5432.538234250093;5418.83743487694;5442.455754424545;5442.727863606824;5448.645838020536;5475.890482190353;5240.6069544784295;5273.86815329616;5282.339707536558;5303.178007408857;5335.359756829205;4822.252550854623;4932.161532539477;5350.0904846176245;5357.892842143175;5361.892762144775;4404.955950440487;5267.631265811395;5329.973350133225;5364.329458817648;5385.165299378603;5576.6375185613215;5610.074337734269;5675.716214189315;5769.134629805562;5782.045962416196;5216.843494695166;6301.117843501914;6352.1742173517605;6373.776917807854;6384.425401713868;6222.937770622281;6298.842528936813;6304.716287767062;6329.082283068929;6334.968325158391;5931.199288096138;5986.790462333797;6016.78941237059;6019.337872833985;6034.48706859913;6372.585781924253;6431.035344698239;6432.71052802625;6434.131392262078;6448.193975753031;6093.573449858556;6127.464420129056;6211.192544969125;6221.782237621662;6260.060990851409;6173.567850250529;6243.282022567372;6251.78118765841;6266.686665666698;6309;6058.242719660043;6115.575014303803;6122.337166850092;6171.228596425447;6244.500439964792;6226.844328891814;6246.625202487865;6248.937510624926;6264.498840092781;6359.8092057238355;4358.5205627380965;5023.723695196726;5044.344235249468;5075.568576671024;5117.232415137759;4471.35165401015;4493.59557639814;4531.116432295671;4531.252343363374;4532.501424843264;5771.971140144273;5967.522598192134;5969.76120955157;5976.252968378953;5990.9101363479585;6227.844303892439;6229.781957631461;6250.249970003538;6309.589876658002;6319.6208227506495;5968.701564921824;5980.671063091571;5989.011813050885;6030.4572588467245;6048.546359023091;5406.216098665672;5449.809256675997;5550.00099981999;5559.861003474864;5567.47109024642;6331.398517140864;6444.065610486453;6470.805875823827;6501.739930402731;6515.771947981798;6284.74861005573;6285.025820997753;6289.874202516015;6298.622082675054;6455;6260.40526150015;6347.714352854225;6355.173827402593;6392.232932048184;6392.296847346695;6293.433590976834;6295.3704629538515;6311.873762524816;6320.6839658016;6342.746290148342;6302.936970630235;6303.9684801575695;6317.96841015792;6321.557490975588;6404;5503.752331145188;6101.9389806103145;6167.352427994996;6168.290646575701;6272.560920735505;5838.357780644211;6115.357887421935;6135.294441139326;6152.692365381624;6181.196444462191;5935.317438494629;6095.219192696128;6097.925212058446;6114.7536090578;6124.969375153096;6094.268687757498;6145.801568694062;6190.7214175363115;6218.036204388146;6275.403836635513;6134.4672016610275;6166.845828854314;6176;6195.845103872439;6201.224846894138 06:38:35 INFO - PROCESS | 1668 | |1;ext.html;497.8133200049981;502.407159551729;524.4336116993646;554.4123229376863;563.712506621623;39251.84114873684;43135.137297254034;43170;43258.567414325844;43299.78350108249;43975.78012109935;44692.77653611736;44938;45013;45027.324590131175;9589.66436174734;9807;9855.310128291023;9856.915739268674;9939;9799.265055120859;9842.60629574816;9867.210623150168;9908.207343412545;9926.950365248164;9880.950595247014;9921.206303495703;9932.702018939442;9958.253131015166;9990.100890919815;9844.753881152958;9895.307328487008;9915.752106197331;9919.057689519484;9943.055409736064;9629.662961796303;9675.274354423447;9725.610975560969;9757.463339516324;9769.51152442375;37918.81040594807;38530.80734596309;41433.1713365734;42157.156856862995;42275.78862105701;3187.378461200067;3357.3285342931267;3451.9482207766905;3505.964940350589;3526.332843149468;26226.86886565555;28082.438351233064;29738.85130574355;31433.84283078593;31508.36983260322;2352.8235382346393;2574.5365834150034;2601.843889366644;2800.2579316481147;2805.130409573039;433619;535334;559241;564419;566197;57351.42648573544;62863.685681571296;66397.66801165963;68207.31792682009;68684;71124.28875711281;72108.27891721016;72253;72371.27628723645;72464;16858.98846069215;16950.898191617507;17130.743038854427;17180.055096969536;17182.14089295555;12942.741145177044;12979.35103244834;12986.935065324613;13147.948164146845;13284.867151328557;18162.638856948775;20533.076011579516;20848.583028339348;20899.164033438796;20899.373018809158;6714.798556043326;6925.480588955848;8061.556614386265;8152.347812175011;8223.712170074019;873.6549063120032;940.9952950235205;1004.5830980143305;1035.3684252605904;1061.0556604621893;27107.72892271052;29696;29937.251568710522;30189.396212076073;30797.538036929465;1043.95824167034;1069.2034434346306;1139.88601139887;1160.6576060062303;1203.2599951030054;15550.688986220439;15644.374225031099;15698.058116512817;15913.840861591236;15966.361345546047;653872;713104;714471;714840;718532.40733796;64387.356126438135;64701.02948455777;64880.70238595202;64969.999999999054;65012.67493662596;1391.0679844504207;1435.9712805743827;1454.861788130126;1456.1408768826443;1458.0449805377282;29177.562336565392;29589.704102958694;29756.256093597833;30576;30817.537736934413;55405.722971386494;55425;55998;56219.156712648524;56936.71531642481;494341;502230;503777;504351;506153;26540.867295663396;27240.77416516222;27659.861700691366;27693.030743923868;27804;48820.75589622029;49350.50649493459;49405.25892111693;49608.255876161165;50025.74987125041;35906;36154.4576831353;37890.62109378871;38112.618873810905;40173.39739903962;38458.80770596129;38904;39389.21221575495;40086.79956600315;40431.19137617172;38678.80660596679;39221;39950.80024599859;40118.79643610696;41922;32039.03882883517;34327.65672343344;34413.20263059933;34971;35048.12379690528;22878.084876605415;23936.73286099178;24190.395240119138;25400.491990160463;25707.742922571284 06:38:35 INFO - PROCESS | 1668 | |2;jquery.html;323.2823132645527;380.10485307101794;380.8895420328105;395.49179304593605;402.42654217739715;19997.60004799905;20585.27951521697;21037.68443473345;21345.93270336483;21363.786362136372;20210.393688189342;20940.476488087766;21376.35870923871;21523.56952860942;21598;5566.6640006398375;5945.4946329562035;6150.907736383958;6174.197354343952;6174.660393678346;5882.235309409771;5969.5821292509545;6060.818175454743;6082.270127584696;6137.386261373871;5777.682227477487;5853.707314634273;5928.851778705523;5970.820875373745;5979.521638268928;5671.659700417947;5829.300483941912;5848.1812546243345;5915.319738230116;5939.910901336483;5148.56237219833;5240.502152295526;5246.029484545371;5256.737163141827;5256.816011439581;25800.74199258002;26338.86830565835;26362;26719.194484579228;26809.597856032175;1919.3186418821397;2202.1631779923687;2229.9108035678714;2236.5750507403704;2316.6177580699164;15179.241037948168;15236.23818809055;15269.61825954349;15421.228938553028;15540.766888496682;1656.8343165683336;1777.8488828449597;1780.2078075256516;1794.255384015645;1836.7520384748022;79147;81783.13759018405;89988.55005724994;110635.44682276617;119169.40415297955;11299.435028248554;11846.703832404259;11869.347185904686;11891.345975971404;11912.285262884254;14442.277886105652;14757.704845903021;14885.33016014282;14929;14954.925225373803;8496.745097647081;8622.240882917531;8645.438046526955;8649.481031138152;8694.391392602458;6868.759593414306;8464.153584641486;8529.488230706052;8573.014103378067;8581.227689507974;4491.438570178728;4569.81720731167;4571.977140114278;4589.495155532889;4664.86005419838;1617.3449752850115;1905.2188602672838;1933.882398773341;1935.8935258560632;1970.6354324450022;858.8926384201975;922.5202894494826;933.50524222161;970.7767213540925;1017.165923942375;16108.516744497683;20578.794212057986;20800.27199048026;20929;21022.894885525475;949.5424523356614;957.6313119448923;992.270681049428;1017.0795430135739;1027.2655051637964;10883.727906802393;11156.553737850558;11173.050290725218;11212.439378031066;11230.7192320191;570594;620726;633266;635647;635781;96603.55094673585;133609.65670474432;136528.2215122486;137684;141547;905.4657751926395;958.166395236149;1016.0601443664729;1020.5305559442592;1024.5901639344322;15385.538433847003;18918.716219256446;19079.904600476908;19126;19242.037898105318;50044.24933626071;50327.74836125796;50788.49211507838;51573.48426515687;52293;75380;76082.47835043157;76134.619326903;77062.2293777055;78537;8003;8031.317338026333;8090.76323630537;8175.509469431853;8372;9024.413413128126;11731.648050558497;12504.062195335204;12894.226346419246;13003.219806811621;10728.088112510524;10731.463426828783;10752.462376880969;10918.617848375268;11105.16711246676;8924;10627.36235825853;10751.247412681038;10868.565257389615;11023.338599684044;9267.907320926974;11021.504032318404;11258.943705281421;11276.267042642203;11565.363904985306;8021;8211.055728591049;8231.38264630167;8267.462614930011;8426.831463370821;10058.29591928558;11091.38997355121;11351.659450216506;11354.318740875575;11384.316940983568 06:38:35 INFO - PROCESS | 1668 | |3;mootools.html;1543.5986643472697;1590.0618635005349;1811.483727137765;1911.8757280776756;1929.6912494000962;8071.273585377315;8733.737987860359;8753.212210901018;9280.81438371233;9285.071492850711;8405.495670259774;8513.063563008063;8544.95727521363;8665;8666.566671666424;2406.9037238510437;2448.400141965221;2451.203358908353;2452.717937437191;2453.717822450414;2418.8669623170717;2433.7931275841574;2444.792192663626;2445.6576079348906;2449.9142530011454;2416.4562973331035;2419.987900060506;2428.1137384854487;2438.195395519483;2455.693038370204;2386.9880650596724;2389.3429306940557;2403.927882163538;2406.362313986786;2416.5770990076844;2381.57131716291;2385.153270588951;2396.9640455393182;2401.5196960607773;2404.1345115758486;7545.5095418798155;9033.367664263504;9060.456372617664;9069.229115525188;9156.725298241063;5387.488188622075;5545.057340252127;5554.86112847178;5572.3870374258795;5584.497395234448;3510;3547.929041419183;3554.2180720241504;3639.253952939641;3639.2721455708697;1021.4841505039982;1025.1081559043605;1034.6533911139777;1040.5473618975693;1076.39721755817;11810.586629467927;11966.581169659106;12151.574694885725;12220.633380998495;12256;4843.75781210938;4882.609391248691;4920.434150072716;4981.775820088104;5002.59979201663;10181.694549163536;10184.745381365525;10267.281290309607;10284.485775711184;10286.074253317238;5409.323834520685;5473.67157970523;5487.6981766002455;5493.176023596413;5494.066008778437;4176.937345939813;4440.578145076212;4836.540528649773;4949.109160351171;5031.270465782441;4012.137390461065;4066.2274167908004;4100.794960251976;4134.607212314825;4142.668586513072;1328.946842126304;1335.138835451148;1336.4988129451456;1351.067763243359;1352.479295471231;833.1210572845584;858.8454078265972;923.1352667611387;926.559884055082;947.1901524196712;5897.351291357947;6015.819525414245;6018.909716354258;6038.094285857156;6079.148919151322;432.3622656581517;437.9868603941887;440.97354158749937;442.9281139642074;452.78945290440106;3765.587904535799;3960.485136932238;3998.660113890294;4025.677945764332;4045.130296986103;9302.209312208402;10747.78504429907;10893.727656808485;10909.890901091047;11120.888791111985;10669.253152279265;10976.012158905736;11051;11078.11375090007;11089.22375433712;8380.832383352299;8426.45228060162;8449.070602233749;8474.957625211959;8478.915210847936;5313.282706834528;5728.968785618462;5837.211976383134;5888.293404791452;5950.2264705587995;10233.079022887976;10380.169586433218;10387.636432725121;10394.688159355232;10396.324238924446;8833.807435996316;9926.404415735078;10206.795864082529;10462.843057354297;10508.264421490423;7096.609686467294;7536.5101268417375;7751.263629955145;7794.883076753967;7971.601419928865;8651.52416617092;8673.479591224546;8723.781905452415;8728.607212675572;8753.387262891536;7600.8479830404185;7696.576688282198;7750.10873749526;7803.648835802289;7844.058712954482;7471.327580517779;7537.6984920604755;7564.50830696003;7574.886376704464;7669;7122.358987691133;7203.963980180066;7204.855902882018;7227.710891564278;7237.661032708966;4779.402574678165;4915.975420122876;4991.6006719463085;5024.221245706921;5027.271045698426;4948.134076536664;5006.774695138654;5102.744862756922;5166.509181627739;5328.547073498641 06:38:35 INFO - PROCESS | 1668 | |4;prototype.html;260.68587352240553;278.63220548875495;311.0838580380779;324.5829109594172;329.38569567756144;4610.9711726840205;5617.21359009739;5634.605577609569;5639.971800141004;5654.604177707557;4719.622430205584;4809.797550612347;5102.387713474379;5547.195656629786;5555.250041244431;3983.880483585497;4146.917061658765;4236.830526778926;4243.575642435762;4271.594198551148;3918.6292293162655;3977.6618987386105;4114.979425102871;4200.894977625553;4258.91482170358;3969.5633480317288;4229.407882896397;4237.491501019882;4249.893752656193;4259.76571288579;3609.6029436761937;3716.535433070866;4061.0659548303906;4210.221109094828;4237.364395340693;3631.8184090795353;3635.763675361093;3660.5424321959754;3692.8892133236045;3699.7965111918834;4456.554344565518;4577.382053422812;5267.315249017642;5274.894502109974;5276.57532466232;4265.637420819234;4473.396091527635;4864.173090574611;4876.707397556158;4897.461279259279;4602.171609110393;4844.903101937976;5369.30866776964;5862.794802181946;5890.997060999238;3978.8806335809973;3980.840766369342;3984.3625019996953;4006.8998275043064;4008.238434697398;5035.0685123252315;5764.538836893039;6204.410580994843;6210.844728881769;6217.222847144107;3957.071766892479;3965.980170099189;3996.541262439189;4014.538328092307;4016.11645438009;3600.955722840347;3989.162275922031;4060.837566497307;4076.1236334188293;4095.2014357200655;4085.5914408558906;4607.06503268628;4626.028534007829;4771.33201351811;4840.443349014909;4972.676776009548;5017.598592112622;5873.442023007808;5894.793682221101;5928.8814223716145;1166.9299842009507;1237.5915947737406;1342.9793357048518;1366.180291824917;1388.9097208681403;717.8313096422337;769.926856948589;837.8282452097305;864.1444969480169;902.8961669408102;5126.564242039469;5391.460853914656;5421.593380496479;5426.810061647902;5431.429699881534;5113.77269455328;5525.640833345819;5617.634853734494;6013.218281623448;6056.939430605726;5040.344755181803;5328.094223981932;5380.757865896044;5388.46115388458;5402.945970540244;2485.2792690120023;2853.6860945296003;3168.7166697487555;3176.698213669698;3197.9360412791616;5988.610740301953;6158.353372895871;6192.628442293477;6223.1287619733275;6235.532335074797;5519.917201241985;5807.5063619591965;6069.514438844882;6083.543734219863;6104.0467050544285;5290.153575427913;5516.641418307797;6191.380861913773;6267.905981410283;6281.120643109968;5091.974540127276;5409.5672346213;5568.331800184003;5776.277965254343;5991.610545314541;5453.700046497322;5861.912071319019;5885.61743486672;5889.906514972054;5948.078047902582;5473.8357849264585;5676.886462270814;5809.82570522885;5965.880682386415;6121.32665406796;3623.4564815277918;3775.697384402377;3958.386450100239;3978.542467616261;4006.419069235003;5275.86568887691;5868.823935281948;5890.617109887844;5912.320083190488;6058.757649693963;4348.369486424387;4662.764367442698;4974.726390048438;5334.439883812144;5763.308402991668;5228.921566176587;5878.941210587839;5884.323302820059;5893.734781934909;5906.881862362815;5429.457054294539;5533.861653458695;5759.9136012959;5775.537956963517;5806.477417032487;5872.765089396547;5889.94110058894;5910.734016969161;5914.970425147847;5920.934231838311;5756.712164391847;5784.681842498703;5791.652500849962;5803.622764520462;5807.506361959281 06:38:35 INFO - PROCESS | 1668 | |5;yui.html;657.9802605921823;674.5109795398337;681.9079424277721;710.4068103133883;717.9138503379594;12243.571474998376;14724.116553006814;15163.924180379096;15344.846551534481;15344.849136314766;13292.800607990888;14061.78907316391;14061.78907316391;14062.78905816411;14079.873610111192;724.3517052238244;890.7016149589895;892.1791951404707;916.8395530782086;918.9908100918972;910.9225715814164;914.8170365926841;914.8856392950881;918.008550765172;921.1939552891221;961.8412961861285;963.3593660215948;963.5423173992333;964.5900492290783;965.2953344058841;954.6658669465701;955.7419496735877;956.2923436656894;958.8417911044704;960.5485421851719;708.4261747984149;708.8511412603359;711.3526690711428;712.4122598855913;713.9250378710265;14410.207438590873;14487.927560362235;14624.634384140376;14657;14764.26178691061;12507.124501284914;12666.176698514568;12671;12679.683007924783;12812.359382030862;9873.358231714987;10096;10122.443265620388;10183.23625728066;10400.583976640924;3472.722182225416;3473.513708080871;3488.7383446241633;3513.350030244413;3560.3235385276716;86912;87757.12242877553;88444;89102.5544872278;89219;6938.410235130071;9369.297302702325;9538.046195380546;9688.515574221261;9774.706758797247;17550.736738948926;21890.79600621981;22256.66615000744;22286.888565557394;22373.993170307374;7272.890906636405;7336.156342020629;7445.9627701861145;7448.217937116633;7581.583012934231;3895.902602434962;3968.6428221460205;3985.8405663773124;3989.082511022481;4001.1797581495725;3408.1479630092476;3467.0465621954163;3509.842057107436;3515.9472607910902;3518.1204698825295;584.6229182177472;617.8918689229367;624.0919462182513;626.1578177351455;630.4231628060273;507.27459732582025;507.3632591098148;596.4453058655405;601.6299975515115;602.5480889333;8086.959565202136;8187.0994190638985;8258.174182581814;8386.329093672492;8519.23326900582;409.57813452143745;554.3735578796015;574.2333984131149;604.4952464692032;630.5586089737202;3271.738859380037;3629.9818500907327;3664.9450258245615;3842.0971071798103;3947.2105578883766;96825.51587242019;97439.51280243554;98407.63535099453;100547;101605;69791;80559.59720201361;83429.33141337139;84247.73628395579;85044.57477712695;76700.84948725774;77418;78137;78551.60724196456;78880;6700.162479690039;7706.383489320953;7782;7864.174261702439;7893.09229438599;36534.63465365312;40907.54961603742;43959.340609890234;44485.77757111194;45445.77277113593;53503.73248133734;55221.17168242399;55461.72269138628;59317.70341148267;62373.752524950156;10808.837867431837;11023.724406889893;11143.72140696489;11237.04485118774;11374.976252137347;11869;11981.460834262654;12309.322987235788;12454.065945054339;12585.181963172365;11285.548578056785;11426.428678566204;11754.059675226132;11885.881141188824;12326.260424374566;11164.441777911234;11220.775584488101;11330.150238731963;11335.979761821476;11734.823977640513;11037.114117239114;11232.269902456641;11294.887051129384;11375.26060806045;11662.475188616363;9542.904570954202;9555.474448905377;9743.091375744494;9771.071748183911;10016.449095299828;9487.784874617999;9833.85249221248;9883.308971087268;10110.342827716175;10130.848037279595 06:38:35 INFO - PROCESS | 1668 | __end_tp_report 06:38:35 INFO - PROCESS | 1668 | __start_cc_report 06:38:35 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,425 06:38:35 INFO - PROCESS | 1668 | __end_cc_report 06:38:35 INFO - PROCESS | 1668 | __startTimestamp1464183515020__endTimestamp 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | ------- Summary: start ------- 06:38:35 INFO - PROCESS | 1668 | Number of tests: 6 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5822.96 Median:6095.22 stddev:649.44 (10.7%) stddev-sans-first:624.67 06:38:35 INFO - PROCESS | 1668 | Values: 3471.1 3498.8 3528.2 3543.1 3550.4 4738.8 5811.7 5875.8 5879.1 5902.1 5590.8 5695.1 5846.6 5850.5 5895.3 5244.4 5278.6 5300.8 5371.7 5432.5 5418.8 5442.5 5442.7 5448.6 5475.9 5240.6 5273.9 5282.3 5303.2 5335.4 4822.3 4932.2 5350.1 5357.9 5361.9 4405.0 5267.6 5330.0 5364.3 5385.2 5576.6 5610.1 5675.7 5769.1 5782.0 5216.8 6301.1 6352.2 6373.8 6384.4 6222.9 6298.8 6304.7 6329.1 6335.0 5931.2 5986.8 6016.8 6019.3 6034.5 6372.6 6431.0 6432.7 6434.1 6448.2 6093.6 6127.5 6211.2 6221.8 6260.1 6173.6 6243.3 6251.8 6266.7 6309.0 6058.2 6115.6 6122.3 6171.2 6244.5 6226.8 6246.6 6248.9 6264.5 6359.8 4358.5 5023.7 5044.3 5075.6 5117.2 4471.4 4493.6 4531.1 4531.3 4532.5 5772.0 5967.5 5969.8 5976.3 5990.9 6227.8 6229.8 6250.2 6309.6 6319.6 5968.7 5980.7 5989.0 6030.5 6048.5 5406.2 5449.8 5550.0 5559.9 5567.5 6331.4 6444.1 6470.8 6501.7 6515.8 6284.7 6285.0 6289.9 6298.6 6455.0 6260.4 6347.7 6355.2 6392.2 6392.3 6293.4 6295.4 6311.9 6320.7 6342.7 6302.9 6304.0 6318.0 6321.6 6404.0 5503.8 6101.9 6167.4 6168.3 6272.6 5838.4 6115.4 6135.3 6152.7 6181.2 5935.3 6095.2 6097.9 6114.8 6125.0 6094.3 6145.8 6190.7 6218.0 6275.4 6134.5 6166.8 6176.0 6195.8 6201.2 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:72127.45 Median:41677.59 stddev:156873.66 (376.4%) stddev-sans-first:157221.55 06:38:35 INFO - PROCESS | 1668 | Values: 497.8 502.4 524.4 554.4 563.7 39251.8 43135.1 43170.0 43258.6 43299.8 43975.8 44692.8 44938.0 45013.0 45027.3 9589.7 9807.0 9855.3 9856.9 9939.0 9799.3 9842.6 9867.2 9908.2 9927.0 9881.0 9921.2 9932.7 9958.3 9990.1 9844.8 9895.3 9915.8 9919.1 9943.1 9629.7 9675.3 9725.6 9757.5 9769.5 37918.8 38530.8 41433.2 42157.2 42275.8 3187.4 3357.3 3451.9 3506.0 3526.3 26226.9 28082.4 29738.9 31433.8 31508.4 2352.8 2574.5 2601.8 2800.3 2805.1 433619.0 535334.0 559241.0 564419.0 566197.0 57351.4 62863.7 66397.7 68207.3 68684.0 71124.3 72108.3 72253.0 72371.3 72464.0 16859.0 16950.9 17130.7 17180.1 17182.1 12942.7 12979.4 12986.9 13147.9 13284.9 18162.6 20533.1 20848.6 20899.2 20899.4 6714.8 6925.5 8061.6 8152.3 8223.7 873.7 941.0 1004.6 1035.4 1061.1 27107.7 29696.0 29937.3 30189.4 30797.5 1044.0 1069.2 1139.9 1160.7 1203.3 15550.7 15644.4 15698.1 15913.8 15966.4 653872.0 713104.0 714471.0 714840.0 718532.4 64387.4 64701.0 64880.7 64970.0 65012.7 1391.1 1436.0 1454.9 1456.1 1458.0 29177.6 29589.7 29756.3 30576.0 30817.5 55405.7 55425.0 55998.0 56219.2 56936.7 494341.0 502230.0 503777.0 504351.0 506153.0 26540.9 27240.8 27659.9 27693.0 27804.0 48820.8 49350.5 49405.3 49608.3 50025.7 35906.0 36154.5 37890.6 38112.6 40173.4 38458.8 38904.0 39389.2 40086.8 40431.2 38678.8 39221.0 39950.8 40118.8 41922.0 32039.0 34327.7 34413.2 34971.0 35048.1 22878.1 23936.7 24190.4 25400.5 25707.7 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35077.01 Median:26350.43 stddev:102864.44 (390.4%) stddev-sans-first:103119.90 06:38:35 INFO - PROCESS | 1668 | Values: 323.3 380.1 380.9 395.5 402.4 19997.6 20585.3 21037.7 21345.9 21363.8 20210.4 20940.5 21376.4 21523.6 21598.0 5566.7 5945.5 6150.9 6174.2 6174.7 5882.2 5969.6 6060.8 6082.3 6137.4 5777.7 5853.7 5928.9 5970.8 5979.5 5671.7 5829.3 5848.2 5915.3 5939.9 5148.6 5240.5 5246.0 5256.7 5256.8 25800.7 26338.9 26362.0 26719.2 26809.6 1919.3 2202.2 2229.9 2236.6 2316.6 15179.2 15236.2 15269.6 15421.2 15540.8 1656.8 1777.8 1780.2 1794.3 1836.8 79147.0 81783.1 89988.6 110635.4 119169.4 11299.4 11846.7 11869.3 11891.3 11912.3 14442.3 14757.7 14885.3 14929.0 14954.9 8496.7 8622.2 8645.4 8649.5 8694.4 6868.8 8464.2 8529.5 8573.0 8581.2 4491.4 4569.8 4572.0 4589.5 4664.9 1617.3 1905.2 1933.9 1935.9 1970.6 858.9 922.5 933.5 970.8 1017.2 16108.5 20578.8 20800.3 20929.0 21022.9 949.5 957.6 992.3 1017.1 1027.3 10883.7 11156.6 11173.1 11212.4 11230.7 570594.0 620726.0 633266.0 635647.0 635781.0 96603.6 133609.7 136528.2 137684.0 141547.0 905.5 958.2 1016.1 1020.5 1024.6 15385.5 18918.7 19079.9 19126.0 19242.0 50044.2 50327.7 50788.5 51573.5 52293.0 75380.0 76082.5 76134.6 77062.2 78537.0 8003.0 8031.3 8090.8 8175.5 8372.0 9024.4 11731.6 12504.1 12894.2 13003.2 10728.1 10731.5 10752.5 10918.6 11105.2 8924.0 10627.4 10751.2 10868.6 11023.3 9267.9 11021.5 11258.9 11276.3 11565.4 8021.0 8211.1 8231.4 8267.5 8426.8 10058.3 11091.4 11351.7 11354.3 11384.3 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5814.19 Median:4840.15 stddev:3282.01 (67.8%) stddev-sans-first:3275.53 06:38:35 INFO - PROCESS | 1668 | Values: 1543.6 1590.1 1811.5 1911.9 1929.7 8071.3 8733.7 8753.2 9280.8 9285.1 8405.5 8513.1 8545.0 8665.0 8666.6 2406.9 2448.4 2451.2 2452.7 2453.7 2418.9 2433.8 2444.8 2445.7 2449.9 2416.5 2420.0 2428.1 2438.2 2455.7 2387.0 2389.3 2403.9 2406.4 2416.6 2381.6 2385.2 2397.0 2401.5 2404.1 7545.5 9033.4 9060.5 9069.2 9156.7 5387.5 5545.1 5554.9 5572.4 5584.5 3510.0 3547.9 3554.2 3639.3 3639.3 1021.5 1025.1 1034.7 1040.5 1076.4 11810.6 11966.6 12151.6 12220.6 12256.0 4843.8 4882.6 4920.4 4981.8 5002.6 10181.7 10184.7 10267.3 10284.5 10286.1 5409.3 5473.7 5487.7 5493.2 5494.1 4176.9 4440.6 4836.5 4949.1 5031.3 4012.1 4066.2 4100.8 4134.6 4142.7 1328.9 1335.1 1336.5 1351.1 1352.5 833.1 858.8 923.1 926.6 947.2 5897.4 6015.8 6018.9 6038.1 6079.1 432.4 438.0 441.0 442.9 452.8 3765.6 3960.5 3998.7 4025.7 4045.1 9302.2 10747.8 10893.7 10909.9 11120.9 10669.3 10976.0 11051.0 11078.1 11089.2 8380.8 8426.5 8449.1 8475.0 8478.9 5313.3 5729.0 5837.2 5888.3 5950.2 10233.1 10380.2 10387.6 10394.7 10396.3 8833.8 9926.4 10206.8 10462.8 10508.3 7096.6 7536.5 7751.3 7794.9 7971.6 8651.5 8673.5 8723.8 8728.6 8753.4 7600.8 7696.6 7750.1 7803.6 7844.1 7471.3 7537.7 7564.5 7574.9 7669.0 7122.4 7204.0 7204.9 7227.7 7237.7 4779.4 4916.0 4991.6 5024.2 5027.3 4948.1 5006.8 5102.7 5166.5 5328.5 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4674.53 Median:5275.38 stddev:1451.93 (27.5%) stddev-sans-first:1417.71 06:38:35 INFO - PROCESS | 1668 | Values: 260.7 278.6 311.1 324.6 329.4 4611.0 5617.2 5634.6 5640.0 5654.6 4719.6 4809.8 5102.4 5547.2 5555.3 3983.9 4146.9 4236.8 4243.6 4271.6 3918.6 3977.7 4115.0 4200.9 4258.9 3969.6 4229.4 4237.5 4249.9 4259.8 3609.6 3716.5 4061.1 4210.2 4237.4 3631.8 3635.8 3660.5 3692.9 3699.8 4456.6 4577.4 5267.3 5274.9 5276.6 4265.6 4473.4 4864.2 4876.7 4897.5 4602.2 4844.9 5369.3 5862.8 5891.0 3978.9 3980.8 3984.4 4006.9 4008.2 5035.1 5764.5 6204.4 6210.8 6217.2 3957.1 3966.0 3996.5 4014.5 4016.1 3601.0 3989.2 4060.8 4076.1 4095.2 4085.6 4607.1 4626.0 4771.3 4840.4 4972.7 5017.6 5873.4 5894.8 5928.9 1166.9 1237.6 1343.0 1366.2 1388.9 717.8 769.9 837.8 864.1 902.9 5126.6 5391.5 5421.6 5426.8 5431.4 5113.8 5525.6 5617.6 6013.2 6056.9 5040.3 5328.1 5380.8 5388.5 5402.9 2485.3 2853.7 3168.7 3176.7 3197.9 5988.6 6158.4 6192.6 6223.1 6235.5 5519.9 5807.5 6069.5 6083.5 6104.0 5290.2 5516.6 6191.4 6267.9 6281.1 5092.0 5409.6 5568.3 5776.3 5991.6 5453.7 5861.9 5885.6 5889.9 5948.1 5473.8 5676.9 5809.8 5965.9 6121.3 3623.5 3775.7 3958.4 3978.5 4006.4 5275.9 5868.8 5890.6 5912.3 6058.8 4348.4 4662.8 4974.7 5334.4 5763.3 5228.9 5878.9 5884.3 5893.7 5906.9 5429.5 5533.9 5759.9 5775.5 5806.5 5872.8 5889.9 5910.7 5915.0 5920.9 5756.7 5784.7 5791.7 5803.6 5807.5 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18433.07 Median:28017.98 stddev:26723.52 (95.4%) stddev-sans-first:26765.15 06:38:35 INFO - PROCESS | 1668 | Values: 658.0 674.5 681.9 710.4 717.9 12243.6 14724.1 15163.9 15344.8 15344.8 13292.8 14061.8 14061.8 14062.8 14079.9 724.4 890.7 892.2 916.8 919.0 910.9 914.8 914.9 918.0 921.2 961.8 963.4 963.5 964.6 965.3 954.7 955.7 956.3 958.8 960.5 708.4 708.9 711.4 712.4 713.9 14410.2 14487.9 14624.6 14657.0 14764.3 12507.1 12666.2 12671.0 12679.7 12812.4 9873.4 10096.0 10122.4 10183.2 10400.6 3472.7 3473.5 3488.7 3513.4 3560.3 86912.0 87757.1 88444.0 89102.6 89219.0 6938.4 9369.3 9538.0 9688.5 9774.7 17550.7 21890.8 22256.7 22286.9 22374.0 7272.9 7336.2 7446.0 7448.2 7581.6 3895.9 3968.6 3985.8 3989.1 4001.2 3408.1 3467.0 3509.8 3515.9 3518.1 584.6 617.9 624.1 626.2 630.4 507.3 507.4 596.4 601.6 602.5 8087.0 8187.1 8258.2 8386.3 8519.2 409.6 554.4 574.2 604.5 630.6 3271.7 3630.0 3664.9 3842.1 3947.2 96825.5 97439.5 98407.6 100547.0 101605.0 69791.0 80559.6 83429.3 84247.7 85044.6 76700.8 77418.0 78137.0 78551.6 78880.0 6700.2 7706.4 7782.0 7864.2 7893.1 36534.6 40907.5 43959.3 44485.8 45445.8 53503.7 55221.2 55461.7 59317.7 62373.8 10808.8 11023.7 11143.7 11237.0 11375.0 11869.0 11981.5 12309.3 12454.1 12585.2 11285.5 11426.4 11754.1 11885.9 12326.3 11164.4 11220.8 11330.2 11336.0 11734.8 11037.1 11232.3 11294.9 11375.3 11662.5 9542.9 9555.5 9743.1 9771.1 10016.4 9487.8 9833.9 9883.3 10110.3 10130.8 06:38:35 INFO - PROCESS | 1668 | -------- Summary: end -------- 06:38:35 INFO - PROCESS | 1668 | 06:38:35 INFO - TEST-INFO | 1668: exit 0 06:38:35 INFO - TEST-OK | dromaeo_css | took 1113496ms 06:38:35 INFO - TEST-START | kraken 06:38:35 INFO - operating with platform_type : mac_ 06:38:35 INFO - Initialising browser for kraken test... 06:38:35 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpmAhXY9/profile http://localhost:49270/getInfo.html) 06:38:38 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 06:38:38 INFO - PROCESS | 1677 | colorDepth:24 06:38:38 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 06:38:38 INFO - PROCESS | 1677 | __metrics 06:38:38 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 06:38:39 INFO - TEST-INFO | 1677: exit 0 06:38:39 INFO - Browser initialized. 06:38:39 INFO - Running cycle 1/1 for kraken test... 06:38:39 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpmAhXY9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:38:51 INFO - PROCESS | 1680 | RSS: Main: 250097664 06:38:51 INFO - PROCESS | 1680 | 06:38:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 06:38:55 INFO - PROCESS | 1680 | RSS: Main: 261980160 06:38:55 INFO - PROCESS | 1680 | 06:38:57 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 06:38:58 INFO - PROCESS | 1680 | RSS: Main: 266727424 06:38:58 INFO - PROCESS | 1680 | 06:39:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 06:39:02 INFO - PROCESS | 1680 | RSS: Main: 267038720 06:39:02 INFO - PROCESS | 1680 | 06:39:04 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 06:39:05 INFO - PROCESS | 1680 | RSS: Main: 267681792 06:39:05 INFO - PROCESS | 1680 | 06:39:07 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:39:07 INFO - PROCESS | 1680 | RSS: Main: 268173312 06:39:07 INFO - PROCESS | 1680 | 06:39:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 06:39:11 INFO - PROCESS | 1680 | RSS: Main: 268460032 06:39:11 INFO - PROCESS | 1680 | 06:39:14 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 06:39:15 INFO - PROCESS | 1680 | RSS: Main: 268808192 06:39:15 INFO - PROCESS | 1680 | 06:39:18 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 06:39:19 INFO - PROCESS | 1680 | RSS: Main: 269078528 06:39:19 INFO - PROCESS | 1680 | 06:39:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:39:21 INFO - PROCESS | 1680 | RSS: Main: 268599296 06:39:21 INFO - PROCESS | 1680 | 06:39:23 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:39:24 INFO - PROCESS | 1680 | RSS: Main: 268668928 06:39:24 INFO - PROCESS | 1680 | 06:39:26 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:39:27 INFO - PROCESS | 1680 | RSS: Main: 269053952 06:39:27 INFO - PROCESS | 1680 | 06:39:29 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:39:30 INFO - PROCESS | 1680 | RSS: Main: 269074432 06:39:30 INFO - PROCESS | 1680 | 06:39:33 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:39:34 INFO - PROCESS | 1680 | RSS: Main: 269082624 06:39:34 INFO - PROCESS | 1680 | 06:39:35 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 06:39:36 INFO - PROCESS | 1680 | RSS: Main: 270983168 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | __start_tp_report 06:39:36 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 06:39:36 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 06:39:36 INFO - PROCESS | 1680 | |i|pagename|runs| 06:39:36 INFO - PROCESS | 1680 | |0;ai-astar;116;111;117;114;115;115;116;117;117;117 06:39:36 INFO - PROCESS | 1680 | |1;audio-beat-detection;177;149;160;195;113;162;169;144;179;126 06:39:36 INFO - PROCESS | 1680 | |2;audio-dft;194;205;250;254;194;194;196;192;237;277 06:39:36 INFO - PROCESS | 1680 | |3;audio-fft;66;84;77;144;57;58;80;82;156;69 06:39:36 INFO - PROCESS | 1680 | |4;audio-oscillator;72;87;120;71;73;81;117;72;72;117 06:39:36 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;135;88;90;87;91;73;86;114;134;106 06:39:36 INFO - PROCESS | 1680 | |6;imaging-darkroom;96;96;119;93;140;97;104;150;150;96 06:39:36 INFO - PROCESS | 1680 | |7;imaging-desaturate;147;144;101;148;134;100;114;142;103;99 06:39:36 INFO - PROCESS | 1680 | |8;json-parse-financial;60;61;65;62;94;60;59;78;62;58 06:39:36 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;69;69;67;76;71;79;75;73;104;61 06:39:36 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;87;88;89;86;90;108;108;112;87;86 06:39:36 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;144;142;141;138;138;140;143;135;190;133 06:39:36 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;166;168;169;170;220;237;231;168;209;150 06:39:36 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;68;66;65;64;68;67;68;69;66;64 06:39:36 INFO - PROCESS | 1680 | __end_tp_report 06:39:36 INFO - PROCESS | 1680 | __start_cc_report 06:39:36 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1096 06:39:36 INFO - PROCESS | 1680 | __end_cc_report 06:39:36 INFO - PROCESS | 1680 | __startTimestamp1464183576111__endTimestamp 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | ------- Summary: start ------- 06:39:36 INFO - PROCESS | 1680 | Number of tests: 14 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:115.50 Median:116.50 stddev:1.90 (1.6%) stddev-sans-first:2.01 06:39:36 INFO - PROCESS | 1680 | Values: 116.0 111.0 117.0 114.0 115.0 115.0 116.0 117.0 117.0 117.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:157.40 Median:165.50 stddev:25.02 (15.1%) stddev-sans-first:25.51 06:39:36 INFO - PROCESS | 1680 | Values: 177.0 149.0 160.0 195.0 113.0 162.0 169.0 144.0 179.0 126.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:219.30 Median:221.00 stddev:31.98 (14.5%) stddev-sans-first:32.58 06:39:36 INFO - PROCESS | 1680 | Values: 194.0 205.0 250.0 254.0 194.0 194.0 196.0 192.0 237.0 277.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:87.30 Median:73.00 stddev:34.48 (47.2%) stddev-sans-first:35.70 06:39:36 INFO - PROCESS | 1680 | Values: 66.0 84.0 77.0 144.0 57.0 58.0 80.0 82.0 156.0 69.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:72.00 stddev:21.18 (29.4%) stddev-sans-first:21.64 06:39:36 INFO - PROCESS | 1680 | Values: 72.0 87.0 120.0 71.0 73.0 81.0 117.0 72.0 72.0 117.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:100.40 Median:86.50 stddev:21.16 (24.5%) stddev-sans-first:18.37 06:39:36 INFO - PROCESS | 1680 | Values: 135.0 88.0 90.0 87.0 91.0 73.0 86.0 114.0 134.0 106.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:114.10 Median:94.50 stddev:23.79 (25.2%) stddev-sans-first:24.32 06:39:36 INFO - PROCESS | 1680 | Values: 96.0 96.0 119.0 93.0 140.0 97.0 104.0 150.0 150.0 96.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:123.20 Median:143.00 stddev:21.59 (15.1%) stddev-sans-first:21.11 06:39:36 INFO - PROCESS | 1680 | Values: 147.0 144.0 101.0 148.0 134.0 100.0 114.0 142.0 103.0 99.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:62.00 stddev:11.41 (18.4%) stddev-sans-first:11.90 06:39:36 INFO - PROCESS | 1680 | Values: 60.0 61.0 65.0 62.0 94.0 60.0 59.0 78.0 62.0 58.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:72.00 stddev:11.58 (16.1%) stddev-sans-first:12.11 06:39:36 INFO - PROCESS | 1680 | Values: 69.0 69.0 67.0 76.0 71.0 79.0 75.0 73.0 104.0 61.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:94.10 Median:87.00 stddev:10.64 (12.2%) stddev-sans-first:10.97 06:39:36 INFO - PROCESS | 1680 | Values: 87.0 88.0 89.0 86.0 90.0 108.0 108.0 112.0 87.0 86.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:141.50 stddev:16.39 (11.6%) stddev-sans-first:17.39 06:39:36 INFO - PROCESS | 1680 | Values: 144.0 142.0 141.0 138.0 138.0 140.0 143.0 135.0 190.0 133.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.80 Median:189.50 stddev:31.84 (16.8%) stddev-sans-first:32.68 06:39:36 INFO - PROCESS | 1680 | Values: 166.0 168.0 169.0 170.0 220.0 237.0 231.0 168.0 209.0 150.0 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.50 stddev:1.78 (2.6%) stddev-sans-first:1.80 06:39:36 INFO - PROCESS | 1680 | Values: 68.0 66.0 65.0 64.0 68.0 67.0 68.0 69.0 66.0 64.0 06:39:36 INFO - PROCESS | 1680 | -------- Summary: end -------- 06:39:36 INFO - PROCESS | 1680 | 06:39:36 INFO - TEST-INFO | 1680: exit 0 06:39:36 INFO - TEST-OK | kraken | took 61319ms 06:39:36 INFO - SUITE-END | took 1174s 06:39:37 INFO - Completed test suite (00:19:35) 06:39:37 INFO - javascript benchmark 06:39:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7284.391989391328, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3471.1322169457635, 3498.8425520851565, 3528.1708798432364, 3543.061088811465, 3550.3964326064565, 4738.8104475820965, 5811.6513009219425, 5875.823725288237, 5879.11813228016, 5902.114682797583, 5590.8043218487255, 5695.117256825189, 5846.649201047941, 5850.502707269877, 5895.292564892219, 5244.449332820056, 5278.551323137519, 5300.761465734032, 5371.650842695231, 5432.538234250093, 5418.83743487694, 5442.455754424545, 5442.727863606824, 5448.645838020536, 5475.890482190353, 5240.6069544784295, 5273.86815329616, 5282.339707536558, 5303.178007408857, 5335.359756829205, 4822.252550854623, 4932.161532539477, 5350.0904846176245, 5357.892842143175, 5361.892762144775, 4404.955950440487, 5267.631265811395, 5329.973350133225, 5364.329458817648, 5385.165299378603, 5576.6375185613215, 5610.074337734269, 5675.716214189315, 5769.134629805562, 5782.045962416196, 5216.843494695166, 6301.117843501914, 6352.1742173517605, 6373.776917807854, 6384.425401713868, 6222.937770622281, 6298.842528936813, 6304.716287767062, 6329.082283068929, 6334.968325158391, 5931.199288096138, 5986.790462333797, 6016.78941237059, 6019.337872833985, 6034.48706859913, 6372.585781924253, 6431.035344698239, 6432.71052802625, 6434.131392262078, 6448.193975753031, 6093.573449858556, 6127.464420129056, 6211.192544969125, 6221.782237621662, 6260.060990851409, 6173.567850250529, 6243.282022567372, 6251.78118765841, 6266.686665666698, 6309.0, 6058.242719660043, 6115.575014303803, 6122.337166850092, 6171.228596425447, 6244.500439964792, 6226.844328891814, 6246.625202487865, 6248.937510624926, 6264.498840092781, 6359.8092057238355, 4358.5205627380965, 5023.723695196726, 5044.344235249468, 5075.568576671024, 5117.232415137759, 4471.35165401015, 4493.59557639814, 4531.116432295671, 4531.252343363374, 4532.501424843264, 5771.971140144273, 5967.522598192134, 5969.76120955157, 5976.252968378953, 5990.9101363479585, 6227.844303892439, 6229.781957631461, 6250.249970003538, 6309.589876658002, 6319.6208227506495, 5968.701564921824, 5980.671063091571, 5989.011813050885, 6030.4572588467245, 6048.546359023091, 5406.216098665672, 5449.809256675997, 5550.00099981999, 5559.861003474864, 5567.47109024642, 6331.398517140864, 6444.065610486453, 6470.805875823827, 6501.739930402731, 6515.771947981798, 6284.74861005573, 6285.025820997753, 6289.874202516015, 6298.622082675054, 6455.0, 6260.40526150015, 6347.714352854225, 6355.173827402593, 6392.232932048184, 6392.296847346695, 6293.433590976834, 6295.3704629538515, 6311.873762524816, 6320.6839658016, 6342.746290148342, 6302.936970630235, 6303.9684801575695, 6317.96841015792, 6321.557490975588, 6404.0, 5503.752331145188, 6101.9389806103145, 6167.352427994996, 6168.290646575701, 6272.560920735505, 5838.357780644211, 6115.357887421935, 6135.294441139326, 6152.692365381624, 6181.196444462191, 5935.317438494629, 6095.219192696128, 6097.925212058446, 6114.7536090578, 6124.969375153096, 6094.268687757498, 6145.801568694062, 6190.7214175363115, 6218.036204388146, 6275.403836635513, 6134.4672016610275, 6166.845828854314, 6176.0, 6195.845103872439, 6201.224846894138], "value": 5783.187791819315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.8133200049981, 502.407159551729, 524.4336116993646, 554.4123229376863, 563.712506621623, 39251.84114873684, 43135.137297254034, 43170.0, 43258.567414325844, 43299.78350108249, 43975.78012109935, 44692.77653611736, 44938.0, 45013.0, 45027.324590131175, 9589.66436174734, 9807.0, 9855.310128291023, 9856.915739268674, 9939.0, 9799.265055120859, 9842.60629574816, 9867.210623150168, 9908.207343412545, 9926.950365248164, 9880.950595247014, 9921.206303495703, 9932.702018939442, 9958.253131015166, 9990.100890919815, 9844.753881152958, 9895.307328487008, 9915.752106197331, 9919.057689519484, 9943.055409736064, 9629.662961796303, 9675.274354423447, 9725.610975560969, 9757.463339516324, 9769.51152442375, 37918.81040594807, 38530.80734596309, 41433.1713365734, 42157.156856862995, 42275.78862105701, 3187.378461200067, 3357.3285342931267, 3451.9482207766905, 3505.964940350589, 3526.332843149468, 26226.86886565555, 28082.438351233064, 29738.85130574355, 31433.84283078593, 31508.36983260322, 2352.8235382346393, 2574.5365834150034, 2601.843889366644, 2800.2579316481147, 2805.130409573039, 433619.0, 535334.0, 559241.0, 564419.0, 566197.0, 57351.42648573544, 62863.685681571296, 66397.66801165963, 68207.31792682009, 68684.0, 71124.28875711281, 72108.27891721016, 72253.0, 72371.27628723645, 72464.0, 16858.98846069215, 16950.898191617507, 17130.743038854427, 17180.055096969536, 17182.14089295555, 12942.741145177044, 12979.35103244834, 12986.935065324613, 13147.948164146845, 13284.867151328557, 18162.638856948775, 20533.076011579516, 20848.583028339348, 20899.164033438796, 20899.373018809158, 6714.798556043326, 6925.480588955848, 8061.556614386265, 8152.347812175011, 8223.712170074019, 873.6549063120032, 940.9952950235205, 1004.5830980143305, 1035.3684252605904, 1061.0556604621893, 27107.72892271052, 29696.0, 29937.251568710522, 30189.396212076073, 30797.538036929465, 1043.95824167034, 1069.2034434346306, 1139.88601139887, 1160.6576060062303, 1203.2599951030054, 15550.688986220439, 15644.374225031099, 15698.058116512817, 15913.840861591236, 15966.361345546047, 653872.0, 713104.0, 714471.0, 714840.0, 718532.40733796, 64387.356126438135, 64701.02948455777, 64880.70238595202, 64969.999999999054, 65012.67493662596, 1391.0679844504207, 1435.9712805743827, 1454.861788130126, 1456.1408768826443, 1458.0449805377282, 29177.562336565392, 29589.704102958694, 29756.256093597833, 30576.0, 30817.537736934413, 55405.722971386494, 55425.0, 55998.0, 56219.156712648524, 56936.71531642481, 494341.0, 502230.0, 503777.0, 504351.0, 506153.0, 26540.867295663396, 27240.77416516222, 27659.861700691366, 27693.030743923868, 27804.0, 48820.75589622029, 49350.50649493459, 49405.25892111693, 49608.255876161165, 50025.74987125041, 35906.0, 36154.4576831353, 37890.62109378871, 38112.618873810905, 40173.39739903962, 38458.80770596129, 38904.0, 39389.21221575495, 40086.79956600315, 40431.19137617172, 38678.80660596679, 39221.0, 39950.80024599859, 40118.79643610696, 41922.0, 32039.03882883517, 34327.65672343344, 34413.20263059933, 34971.0, 35048.12379690528, 22878.084876605415, 23936.73286099178, 24190.395240119138, 25400.491990160463, 25707.742922571284], "value": 20479.939682145727, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.2823132645527, 380.10485307101794, 380.8895420328105, 395.49179304593605, 402.42654217739715, 19997.60004799905, 20585.27951521697, 21037.68443473345, 21345.93270336483, 21363.786362136372, 20210.393688189342, 20940.476488087766, 21376.35870923871, 21523.56952860942, 21598.0, 5566.6640006398375, 5945.4946329562035, 6150.907736383958, 6174.197354343952, 6174.660393678346, 5882.235309409771, 5969.5821292509545, 6060.818175454743, 6082.270127584696, 6137.386261373871, 5777.682227477487, 5853.707314634273, 5928.851778705523, 5970.820875373745, 5979.521638268928, 5671.659700417947, 5829.300483941912, 5848.1812546243345, 5915.319738230116, 5939.910901336483, 5148.56237219833, 5240.502152295526, 5246.029484545371, 5256.737163141827, 5256.816011439581, 25800.74199258002, 26338.86830565835, 26362.0, 26719.194484579228, 26809.597856032175, 1919.3186418821397, 2202.1631779923687, 2229.9108035678714, 2236.5750507403704, 2316.6177580699164, 15179.241037948168, 15236.23818809055, 15269.61825954349, 15421.228938553028, 15540.766888496682, 1656.8343165683336, 1777.8488828449597, 1780.2078075256516, 1794.255384015645, 1836.7520384748022, 79147.0, 81783.13759018405, 89988.55005724994, 110635.44682276617, 119169.40415297955, 11299.435028248554, 11846.703832404259, 11869.347185904686, 11891.345975971404, 11912.285262884254, 14442.277886105652, 14757.704845903021, 14885.33016014282, 14929.0, 14954.925225373803, 8496.745097647081, 8622.240882917531, 8645.438046526955, 8649.481031138152, 8694.391392602458, 6868.759593414306, 8464.153584641486, 8529.488230706052, 8573.014103378067, 8581.227689507974, 4491.438570178728, 4569.81720731167, 4571.977140114278, 4589.495155532889, 4664.86005419838, 1617.3449752850115, 1905.2188602672838, 1933.882398773341, 1935.8935258560632, 1970.6354324450022, 858.8926384201975, 922.5202894494826, 933.50524222161, 970.7767213540925, 1017.165923942375, 16108.516744497683, 20578.794212057986, 20800.27199048026, 20929.0, 21022.894885525475, 949.5424523356614, 957.6313119448923, 992.270681049428, 1017.0795430135739, 1027.2655051637964, 10883.727906802393, 11156.553737850558, 11173.050290725218, 11212.439378031066, 11230.7192320191, 570594.0, 620726.0, 633266.0, 635647.0, 635781.0, 96603.55094673585, 133609.65670474432, 136528.2215122486, 137684.0, 141547.0, 905.4657751926395, 958.166395236149, 1016.0601443664729, 1020.5305559442592, 1024.5901639344322, 15385.538433847003, 18918.716219256446, 19079.904600476908, 19126.0, 19242.037898105318, 50044.24933626071, 50327.74836125796, 50788.49211507838, 51573.48426515687, 52293.0, 75380.0, 76082.47835043157, 76134.619326903, 77062.2293777055, 78537.0, 8003.0, 8031.317338026333, 8090.76323630537, 8175.509469431853, 8372.0, 9024.413413128126, 11731.648050558497, 12504.062195335204, 12894.226346419246, 13003.219806811621, 10728.088112510524, 10731.463426828783, 10752.462376880969, 10918.617848375268, 11105.16711246676, 8924.0, 10627.36235825853, 10751.247412681038, 10868.565257389615, 11023.338599684044, 9267.907320926974, 11021.504032318404, 11258.943705281421, 11276.267042642203, 11565.363904985306, 8021.0, 8211.055728591049, 8231.38264630167, 8267.462614930011, 8426.831463370821, 10058.29591928558, 11091.38997355121, 11351.659450216506, 11354.318740875575, 11384.316940983568], "value": 9583.819583099286, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.5986643472697, 1590.0618635005349, 1811.483727137765, 1911.8757280776756, 1929.6912494000962, 8071.273585377315, 8733.737987860359, 8753.212210901018, 9280.81438371233, 9285.071492850711, 8405.495670259774, 8513.063563008063, 8544.95727521363, 8665.0, 8666.566671666424, 2406.9037238510437, 2448.400141965221, 2451.203358908353, 2452.717937437191, 2453.717822450414, 2418.8669623170717, 2433.7931275841574, 2444.792192663626, 2445.6576079348906, 2449.9142530011454, 2416.4562973331035, 2419.987900060506, 2428.1137384854487, 2438.195395519483, 2455.693038370204, 2386.9880650596724, 2389.3429306940557, 2403.927882163538, 2406.362313986786, 2416.5770990076844, 2381.57131716291, 2385.153270588951, 2396.9640455393182, 2401.5196960607773, 2404.1345115758486, 7545.5095418798155, 9033.367664263504, 9060.456372617664, 9069.229115525188, 9156.725298241063, 5387.488188622075, 5545.057340252127, 5554.86112847178, 5572.3870374258795, 5584.497395234448, 3510.0, 3547.929041419183, 3554.2180720241504, 3639.253952939641, 3639.2721455708697, 1021.4841505039982, 1025.1081559043605, 1034.6533911139777, 1040.5473618975693, 1076.39721755817, 11810.586629467927, 11966.581169659106, 12151.574694885725, 12220.633380998495, 12256.0, 4843.75781210938, 4882.609391248691, 4920.434150072716, 4981.775820088104, 5002.59979201663, 10181.694549163536, 10184.745381365525, 10267.281290309607, 10284.485775711184, 10286.074253317238, 5409.323834520685, 5473.67157970523, 5487.6981766002455, 5493.176023596413, 5494.066008778437, 4176.937345939813, 4440.578145076212, 4836.540528649773, 4949.109160351171, 5031.270465782441, 4012.137390461065, 4066.2274167908004, 4100.794960251976, 4134.607212314825, 4142.668586513072, 1328.946842126304, 1335.138835451148, 1336.4988129451456, 1351.067763243359, 1352.479295471231, 833.1210572845584, 858.8454078265972, 923.1352667611387, 926.559884055082, 947.1901524196712, 5897.351291357947, 6015.819525414245, 6018.909716354258, 6038.094285857156, 6079.148919151322, 432.3622656581517, 437.9868603941887, 440.97354158749937, 442.9281139642074, 452.78945290440106, 3765.587904535799, 3960.485136932238, 3998.660113890294, 4025.677945764332, 4045.130296986103, 9302.209312208402, 10747.78504429907, 10893.727656808485, 10909.890901091047, 11120.888791111985, 10669.253152279265, 10976.012158905736, 11051.0, 11078.11375090007, 11089.22375433712, 8380.832383352299, 8426.45228060162, 8449.070602233749, 8474.957625211959, 8478.915210847936, 5313.282706834528, 5728.968785618462, 5837.211976383134, 5888.293404791452, 5950.2264705587995, 10233.079022887976, 10380.169586433218, 10387.636432725121, 10394.688159355232, 10396.324238924446, 8833.807435996316, 9926.404415735078, 10206.795864082529, 10462.843057354297, 10508.264421490423, 7096.609686467294, 7536.5101268417375, 7751.263629955145, 7794.883076753967, 7971.601419928865, 8651.52416617092, 8673.479591224546, 8723.781905452415, 8728.607212675572, 8753.387262891536, 7600.8479830404185, 7696.576688282198, 7750.10873749526, 7803.648835802289, 7844.058712954482, 7471.327580517779, 7537.6984920604755, 7564.50830696003, 7574.886376704464, 7669.0, 7122.358987691133, 7203.963980180066, 7204.855902882018, 7227.710891564278, 7237.661032708966, 4779.402574678165, 4915.975420122876, 4991.6006719463085, 5024.221245706921, 5027.271045698426, 4948.134076536664, 5006.774695138654, 5102.744862756922, 5166.509181627739, 5328.547073498641], "value": 4589.833309596076, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.68587352240553, 278.63220548875495, 311.0838580380779, 324.5829109594172, 329.38569567756144, 4610.9711726840205, 5617.21359009739, 5634.605577609569, 5639.971800141004, 5654.604177707557, 4719.622430205584, 4809.797550612347, 5102.387713474379, 5547.195656629786, 5555.250041244431, 3983.880483585497, 4146.917061658765, 4236.830526778926, 4243.575642435762, 4271.594198551148, 3918.6292293162655, 3977.6618987386105, 4114.979425102871, 4200.894977625553, 4258.91482170358, 3969.5633480317288, 4229.407882896397, 4237.491501019882, 4249.893752656193, 4259.76571288579, 3609.6029436761937, 3716.535433070866, 4061.0659548303906, 4210.221109094828, 4237.364395340693, 3631.8184090795353, 3635.763675361093, 3660.5424321959754, 3692.8892133236045, 3699.7965111918834, 4456.554344565518, 4577.382053422812, 5267.315249017642, 5274.894502109974, 5276.57532466232, 4265.637420819234, 4473.396091527635, 4864.173090574611, 4876.707397556158, 4897.461279259279, 4602.171609110393, 4844.903101937976, 5369.30866776964, 5862.794802181946, 5890.997060999238, 3978.8806335809973, 3980.840766369342, 3984.3625019996953, 4006.8998275043064, 4008.238434697398, 5035.0685123252315, 5764.538836893039, 6204.410580994843, 6210.844728881769, 6217.222847144107, 3957.071766892479, 3965.980170099189, 3996.541262439189, 4014.538328092307, 4016.11645438009, 3600.955722840347, 3989.162275922031, 4060.837566497307, 4076.1236334188293, 4095.2014357200655, 4085.5914408558906, 4607.06503268628, 4626.028534007829, 4771.33201351811, 4840.443349014909, 4972.676776009548, 5017.598592112622, 5873.442023007808, 5894.793682221101, 5928.8814223716145, 1166.9299842009507, 1237.5915947737406, 1342.9793357048518, 1366.180291824917, 1388.9097208681403, 717.8313096422337, 769.926856948589, 837.8282452097305, 864.1444969480169, 902.8961669408102, 5126.564242039469, 5391.460853914656, 5421.593380496479, 5426.810061647902, 5431.429699881534, 5113.77269455328, 5525.640833345819, 5617.634853734494, 6013.218281623448, 6056.939430605726, 5040.344755181803, 5328.094223981932, 5380.757865896044, 5388.46115388458, 5402.945970540244, 2485.2792690120023, 2853.6860945296003, 3168.7166697487555, 3176.698213669698, 3197.9360412791616, 5988.610740301953, 6158.353372895871, 6192.628442293477, 6223.1287619733275, 6235.532335074797, 5519.917201241985, 5807.5063619591965, 6069.514438844882, 6083.543734219863, 6104.0467050544285, 5290.153575427913, 5516.641418307797, 6191.380861913773, 6267.905981410283, 6281.120643109968, 5091.974540127276, 5409.5672346213, 5568.331800184003, 5776.277965254343, 5991.610545314541, 5453.700046497322, 5861.912071319019, 5885.61743486672, 5889.906514972054, 5948.078047902582, 5473.8357849264585, 5676.886462270814, 5809.82570522885, 5965.880682386415, 6121.32665406796, 3623.4564815277918, 3775.697384402377, 3958.386450100239, 3978.542467616261, 4006.419069235003, 5275.86568887691, 5868.823935281948, 5890.617109887844, 5912.320083190488, 6058.757649693963, 4348.369486424387, 4662.764367442698, 4974.726390048438, 5334.439883812144, 5763.308402991668, 5228.921566176587, 5878.941210587839, 5884.323302820059, 5893.734781934909, 5906.881862362815, 5429.457054294539, 5533.861653458695, 5759.9136012959, 5775.537956963517, 5806.477417032487, 5872.765089396547, 5889.94110058894, 5910.734016969161, 5914.970425147847, 5920.934231838311, 5756.712164391847, 5784.681842498703, 5791.652500849962, 5803.622764520462, 5807.506361959281], "value": 4196.508505977237, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.9802605921823, 674.5109795398337, 681.9079424277721, 710.4068103133883, 717.9138503379594, 12243.571474998376, 14724.116553006814, 15163.924180379096, 15344.846551534481, 15344.849136314766, 13292.800607990888, 14061.78907316391, 14061.78907316391, 14062.78905816411, 14079.873610111192, 724.3517052238244, 890.7016149589895, 892.1791951404707, 916.8395530782086, 918.9908100918972, 910.9225715814164, 914.8170365926841, 914.8856392950881, 918.008550765172, 921.1939552891221, 961.8412961861285, 963.3593660215948, 963.5423173992333, 964.5900492290783, 965.2953344058841, 954.6658669465701, 955.7419496735877, 956.2923436656894, 958.8417911044704, 960.5485421851719, 708.4261747984149, 708.8511412603359, 711.3526690711428, 712.4122598855913, 713.9250378710265, 14410.207438590873, 14487.927560362235, 14624.634384140376, 14657.0, 14764.26178691061, 12507.124501284914, 12666.176698514568, 12671.0, 12679.683007924783, 12812.359382030862, 9873.358231714987, 10096.0, 10122.443265620388, 10183.23625728066, 10400.583976640924, 3472.722182225416, 3473.513708080871, 3488.7383446241633, 3513.350030244413, 3560.3235385276716, 86912.0, 87757.12242877553, 88444.0, 89102.5544872278, 89219.0, 6938.410235130071, 9369.297302702325, 9538.046195380546, 9688.515574221261, 9774.706758797247, 17550.736738948926, 21890.79600621981, 22256.66615000744, 22286.888565557394, 22373.993170307374, 7272.890906636405, 7336.156342020629, 7445.9627701861145, 7448.217937116633, 7581.583012934231, 3895.902602434962, 3968.6428221460205, 3985.8405663773124, 3989.082511022481, 4001.1797581495725, 3408.1479630092476, 3467.0465621954163, 3509.842057107436, 3515.9472607910902, 3518.1204698825295, 584.6229182177472, 617.8918689229367, 624.0919462182513, 626.1578177351455, 630.4231628060273, 507.27459732582025, 507.3632591098148, 596.4453058655405, 601.6299975515115, 602.5480889333, 8086.959565202136, 8187.0994190638985, 8258.174182581814, 8386.329093672492, 8519.23326900582, 409.57813452143745, 554.3735578796015, 574.2333984131149, 604.4952464692032, 630.5586089737202, 3271.738859380037, 3629.9818500907327, 3664.9450258245615, 3842.0971071798103, 3947.2105578883766, 96825.51587242019, 97439.51280243554, 98407.63535099453, 100547.0, 101605.0, 69791.0, 80559.59720201361, 83429.33141337139, 84247.73628395579, 85044.57477712695, 76700.84948725774, 77418.0, 78137.0, 78551.60724196456, 78880.0, 6700.162479690039, 7706.383489320953, 7782.0, 7864.174261702439, 7893.09229438599, 36534.63465365312, 40907.54961603742, 43959.340609890234, 44485.77757111194, 45445.77277113593, 53503.73248133734, 55221.17168242399, 55461.72269138628, 59317.70341148267, 62373.752524950156, 10808.837867431837, 11023.724406889893, 11143.72140696489, 11237.04485118774, 11374.976252137347, 11869.0, 11981.460834262654, 12309.322987235788, 12454.065945054339, 12585.181963172365, 11285.548578056785, 11426.428678566204, 11754.059675226132, 11885.881141188824, 12326.260424374566, 11164.441777911234, 11220.775584488101, 11330.150238731963, 11335.979761821476, 11734.823977640513, 11037.114117239114, 11232.269902456641, 11294.887051129384, 11375.26060806045, 11662.475188616363, 9542.904570954202, 9555.474448905377, 9743.091375744494, 9771.071748183911, 10016.449095299828, 9487.784874617999, 9833.85249221248, 9883.308971087268, 10110.342827716175, 10130.848037279595], "value": 6832.736497679184, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1639.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 111.0, 117.0, 114.0, 115.0, 115.0, 116.0, 117.0, 117.0, 117.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 149.0, 160.0, 195.0, 113.0, 162.0, 169.0, 144.0, 179.0, 126.0], "value": 157.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 205.0, 250.0, 254.0, 194.0, 194.0, 196.0, 192.0, 237.0, 277.0], "value": 219.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 84.0, 77.0, 144.0, 57.0, 58.0, 80.0, 82.0, 156.0, 69.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 87.0, 120.0, 71.0, 73.0, 81.0, 117.0, 72.0, 72.0, 117.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 88.0, 90.0, 87.0, 91.0, 73.0, 86.0, 114.0, 134.0, 106.0], "value": 100.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 119.0, 93.0, 140.0, 97.0, 104.0, 150.0, 150.0, 96.0], "value": 114.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 144.0, 101.0, 148.0, 134.0, 100.0, 114.0, 142.0, 103.0, 99.0], "value": 123.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 65.0, 62.0, 94.0, 60.0, 59.0, 78.0, 62.0, 58.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 67.0, 76.0, 71.0, 79.0, 75.0, 73.0, 104.0, 61.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 89.0, 86.0, 90.0, 108.0, 108.0, 112.0, 87.0, 86.0], "value": 94.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 142.0, 141.0, 138.0, 138.0, 140.0, 143.0, 135.0, 190.0, 133.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 169.0, 170.0, 220.0, 237.0, 231.0, 168.0, 209.0, 150.0], "value": 188.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 65.0, 64.0, 68.0, 67.0, 68.0, 69.0, 66.0, 64.0], "value": 66.5, "unit": "score"}]}]} 06:39:37 INFO - Return code: 0 06:39:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:39:37 ERROR - # TBPL SUCCESS # 06:39:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:39:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:39:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:39:37 INFO - 2016-05-25 06:39:37.445 screenresolution[1685:10559] starting screenresolution argv=screenresolution get 06:39:37 INFO - 2016-05-25 06:39:37.448 screenresolution[1685:10559] Display 0: 1600x1200x32@60 06:39:37 INFO - 2016-05-25 06:39:37.453 screenresolution[1686:10562] starting screenresolution argv=screenresolution list 06:39:37 INFO - Available Modes on Display 0 06:39:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:39:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:39:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:39:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:39:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:39:37 INFO - Intel Iris: 06:39:37 INFO - Chipset Model: Intel Iris 06:39:37 INFO - Type: GPU 06:39:37 INFO - Bus: Built-In 06:39:37 INFO - VRAM (Dynamic, Max): 1536 MB 06:39:37 INFO - Vendor: Intel (0x8086) 06:39:37 INFO - Device ID: 0x0a2e 06:39:37 INFO - Revision ID: 0x0009 06:39:37 INFO - Displays: 06:39:37 INFO - Display: 06:39:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:39:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:39:37 INFO - Display Serial Number: 1600x1200 60 06:39:37 INFO - Main Display: Yes 06:39:37 INFO - Mirror: Off 06:39:37 INFO - Online: Yes 06:39:37 INFO - Rotation: Supported 06:39:37 INFO - Return code: 0 06:39:37 INFO - Running post-action listener: _resource_record_post_action 06:39:37 INFO - ##### 06:39:37 INFO - ##### Finished run-tests step (success) 06:39:37 INFO - ##### 06:39:37 INFO - Running post-run listener: _resource_record_post_run 06:39:37 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32722432; Write bytes: 699101184; Read time: 314; Write time: 1889 06:39:37 INFO - TinderboxPrint: CPU usage
54.3% 06:39:37 INFO - TinderboxPrint: I/O read bytes / time
32,722,432 / 314 06:39:37 INFO - TinderboxPrint: I/O write bytes / time
699,101,184 / 1,889 06:39:37 INFO - TinderboxPrint: CPU idle
2,213.6 (45.7%) 06:39:37 INFO - TinderboxPrint: CPU system
976.6 (20.2%) 06:39:37 INFO - TinderboxPrint: CPU user
1,655.6 (34.2%) 06:39:37 INFO - TinderboxPrint: Swap in / out
425,607,168 / 0 06:39:37 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 388407808; Write bytes: 383076352; Read time: 29667; Write time: 673 06:39:37 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30928384; Write bytes: 298641408; Read time: 295; Write time: 1177 06:39:38 INFO - Running post-run listener: _upload_blobber_files 06:39:38 INFO - Blob upload gear active. 06:39:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:39:38 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 06:39:38 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'] 06:39:38 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 06:39:38 INFO - (blobuploader) - INFO - Open directory for files ... 06:39:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:39:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:39:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:39 INFO - (blobuploader) - INFO - Done attempting. 06:39:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:39:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:39:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:40 INFO - (blobuploader) - INFO - Done attempting. 06:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:39:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:44 INFO - (blobuploader) - INFO - Done attempting. 06:39:44 INFO - (blobuploader) - INFO - Iteration through files over. 06:39:44 INFO - Return code: 0 06:39:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:39:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:39:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e468495b543643ebdf20ee194a4a855e8091695eba69b1ba97e5a3ebf63647df8aa7e045926f4b6681237b5ecb9eda7065e40ef42bb74ab64dc6f712b9b5972", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c357aff764f5c8590b181ec01f2e481593663824da321989a3c7269d59cb4d6625883451f046541e2d0f7ca35ecbfb90c8b609cf29c7dfd62a02c6702147652", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e56202d67874230c111986939068c3e23eef9618af2373f18cca966a4863ce6d847f8fdc5b8ed8aab3e693048b54dc75226db92ae0bb54eff372126301aa024"} 06:39:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:39:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:39:44 INFO - Contents: 06:39:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e468495b543643ebdf20ee194a4a855e8091695eba69b1ba97e5a3ebf63647df8aa7e045926f4b6681237b5ecb9eda7065e40ef42bb74ab64dc6f712b9b5972", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c357aff764f5c8590b181ec01f2e481593663824da321989a3c7269d59cb4d6625883451f046541e2d0f7ca35ecbfb90c8b609cf29c7dfd62a02c6702147652", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e56202d67874230c111986939068c3e23eef9618af2373f18cca966a4863ce6d847f8fdc5b8ed8aab3e693048b54dc75226db92ae0bb54eff372126301aa024"} 06:39:44 INFO - Running post-run listener: copy_logs_to_upload_dir 06:39:44 INFO - Copying logs to upload dir... 06:39:44 INFO - mkdir: /builds/slave/test/build/upload/logs 06:39:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.749553 ========= master_lag: 202.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 47 secs) (at 2016-05-25 06:43:06.489682) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-05-25 06:43:06.493477) ========= 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.viOknWGlTC/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.zmEmJDqYUj/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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/1e468495b543643ebdf20ee194a4a855e8091695eba69b1ba97e5a3ebf63647df8aa7e045926f4b6681237b5ecb9eda7065e40ef42bb74ab64dc6f712b9b5972", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c357aff764f5c8590b181ec01f2e481593663824da321989a3c7269d59cb4d6625883451f046541e2d0f7ca35ecbfb90c8b609cf29c7dfd62a02c6702147652", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e56202d67874230c111986939068c3e23eef9618af2373f18cca966a4863ce6d847f8fdc5b8ed8aab3e693048b54dc75226db92ae0bb54eff372126301aa024"} build_url:https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/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/1e468495b543643ebdf20ee194a4a855e8091695eba69b1ba97e5a3ebf63647df8aa7e045926f4b6681237b5ecb9eda7065e40ef42bb74ab64dc6f712b9b5972", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c357aff764f5c8590b181ec01f2e481593663824da321989a3c7269d59cb4d6625883451f046541e2d0f7ca35ecbfb90c8b609cf29c7dfd62a02c6702147652", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e56202d67874230c111986939068c3e23eef9618af2373f18cca966a4863ce6d847f8fdc5b8ed8aab3e693048b54dc75226db92ae0bb54eff372126301aa024"}' ========= master_lag: 1.99 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-05-25 06:43:08.493872) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 18 secs) (at 2016-05-25 06:43:08.494195) ========= 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.viOknWGlTC/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.zmEmJDqYUj/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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.004897 ========= master_lag: 18.23 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 18 secs) (at 2016-05-25 06:43:26.732490) ========= ========= Started reboot skipped (results: 3, elapsed: 8 secs) (at 2016-05-25 06:43:26.732782) ========= ========= Finished reboot skipped (results: 3, elapsed: 8 secs) (at 2016-05-25 06:43:34.784503) ========= ========= Total master_lag: 222.52 =========