builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0036 starttime: 1463262161.59 results: success (0) buildid: 20160514130527 builduid: 2ff7712dfe204a41a0a665e4771196ee revision: 403912ca555eb65f814b18ecf38ad8e8e98569f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:41.589000) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:41.589438) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:41.589718) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:41.607220) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:41.607544) ========= 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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-14 14:42:41-- 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% 14.3M=0.001s 2016-05-14 14:42:42 (14.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.457934 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:42.082091) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:42.082450) ========= 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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.019960 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:42.131022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 14:42:42.131377) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 403912ca555eb65f814b18ecf38ad8e8e98569f5 --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 403912ca555eb65f814b18ecf38ad8e8e98569f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-14 14:42:42,218 truncating revision to first 12 chars 2016-05-14 14:42:42,218 Setting DEBUG logging. 2016-05-14 14:42:42,218 attempt 1/10 2016-05-14 14:42:42,218 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/403912ca555e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 14:42:42,975 unpacking tar archive at: mozilla-central-403912ca555e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.003902 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 14:42:43.152318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:43.152657) ========= script_repo_revision: 403912ca555eb65f814b18ecf38ad8e8e98569f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:43.153031) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:43.153327) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 14:42:43.167223) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 50 secs) (at 2016-05-14 14:42:43.167615) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:42:43 INFO - MultiFileLogger online at 20160514 14:42:43 in /builds/slave/test 14:42:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:42:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:42:43 INFO - {'append_to_log': False, 14:42:43 INFO - 'base_work_dir': '/builds/slave/test', 14:42:43 INFO - 'blob_upload_branch': 'Firefox', 14:42:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:42:43 INFO - 'branch': 'Firefox', 14:42:43 INFO - 'buildbot_json_path': 'buildprops.json', 14:42:43 INFO - 'config_files': ('talos/mac_config.py',), 14:42:43 INFO - 'default_actions': ('clobber', 14:42:43 INFO - 'read-buildbot-config', 14:42:43 INFO - 'download-and-extract', 14:42:43 INFO - 'populate-webroot', 14:42:43 INFO - 'create-virtualenv', 14:42:43 INFO - 'install', 14:42:43 INFO - 'run-tests'), 14:42:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:42:43 INFO - 'download_minidump_stackwalk': True, 14:42:43 INFO - 'download_symbols': 'ondemand', 14:42:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:42:43 INFO - 'tooltool.py': '/tools/tooltool.py', 14:42:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:42:43 INFO - '/tools/misc-python/virtualenv.py')}, 14:42:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:42:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:42:43 INFO - 'installer_path': 'installer.exe', 14:42:43 INFO - 'log_level': 'info', 14:42:43 INFO - 'log_name': 'talos', 14:42:43 INFO - 'log_to_console': True, 14:42:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:42:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:42:43 INFO - 'opt_config_files': (), 14:42:43 INFO - 'pip_index': False, 14:42:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:42:43 INFO - 'cmd': ('bash', 14:42:43 INFO - '-c', 14:42:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:42:43 INFO - 'enabled': True, 14:42:43 INFO - 'halt_on_failure': False, 14:42:43 INFO - 'name': 'check_screen_resolution'},), 14:42:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:42:43 INFO - 'cmd': ('bash', 14:42:43 INFO - '-c', 14:42:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:42:43 INFO - 'enabled': True, 14:42:43 INFO - 'halt_on_failure': False, 14:42:43 INFO - 'name': 'check_screen_resolution'},), 14:42:43 INFO - 'run_cmd_checks_enabled': True, 14:42:43 INFO - 'sps_profile': False, 14:42:43 INFO - 'sps_profile_interval': 0, 14:42:43 INFO - 'suite': 'dromaeojs', 14:42:43 INFO - 'system_bits': '32', 14:42:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:42:43 INFO - 'title': 't-yosemite-r7-0036', 14:42:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:42:43 INFO - 'use_talos_json': True, 14:42:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:42:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:42:43 INFO - 'work_dir': 'build'} 14:42:43 INFO - ##### 14:42:43 INFO - ##### Running clobber step. 14:42:43 INFO - ##### 14:42:43 INFO - Running pre-action listener: _resource_record_pre_action 14:42:43 INFO - Running main action method: clobber 14:42:43 INFO - rmtree: /builds/slave/test/build 14:42:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:42:44 INFO - Running post-action listener: _resource_record_post_action 14:42:44 INFO - ##### 14:42:44 INFO - ##### Finished clobber step (success) 14:42:44 INFO - ##### 14:42:44 INFO - ##### 14:42:44 INFO - ##### Running read-buildbot-config step. 14:42:44 INFO - ##### 14:42:44 INFO - Running pre-action listener: _resource_record_pre_action 14:42:44 INFO - Running main action method: read_buildbot_config 14:42:44 INFO - Using buildbot properties: 14:42:44 INFO - { 14:42:44 INFO - "project": "", 14:42:44 INFO - "product": "firefox", 14:42:44 INFO - "script_repo_revision": "production", 14:42:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:42:44 INFO - "repository": "", 14:42:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:42:44 INFO - "buildid": "20160514130527", 14:42:44 INFO - "pgo_build": "False", 14:42:44 INFO - "basedir": "/builds/slave/test", 14:42:44 INFO - "buildnumber": 8, 14:42:44 INFO - "slavename": "t-yosemite-r7-0036", 14:42:44 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:42:44 INFO - "platform": "yosemite_r7", 14:42:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:42:44 INFO - "branch": "mozilla-central", 14:42:44 INFO - "repo_path": "mozilla-central", 14:42:44 INFO - "revision": "403912ca555eb65f814b18ecf38ad8e8e98569f5", 14:42:44 INFO - "stage_platform": "macosx64", 14:42:44 INFO - "builduid": "2ff7712dfe204a41a0a665e4771196ee", 14:42:44 INFO - "slavebuilddir": "test" 14:42:44 INFO - } 14:42:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:42:44 INFO - Running post-action listener: _resource_record_post_action 14:42:44 INFO - ##### 14:42:44 INFO - ##### Finished read-buildbot-config step (success) 14:42:44 INFO - ##### 14:42:44 INFO - ##### 14:42:44 INFO - ##### Running download-and-extract step. 14:42:44 INFO - ##### 14:42:44 INFO - Running pre-action listener: _resource_record_pre_action 14:42:44 INFO - Running main action method: download_and_extract 14:42:44 INFO - mkdir: /builds/slave/test/build/tests 14:42: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')]} 14:42:44 INFO - https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:42:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:42:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:42:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:42:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:43:08 INFO - Downloaded 1199 bytes. 14:43:08 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:43:08 INFO - Using the following test package requirements: 14:43:08 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:43:08 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:43:08 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:43:08 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:43:08 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:43:08 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:43:08 INFO - u'jsshell-mac.zip'], 14:43:08 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:43:08 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:43:08 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:43:08 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:43:08 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:43:08 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:43:08 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:43:08 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:43:08 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:43:08 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:43:08 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:43:08 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:43:08 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')]} 14:43:08 INFO - https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:43:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:43:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:43:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:43:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:43:10 INFO - Downloaded 18267589 bytes. 14:43:10 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'] 14:43:10 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 14:43:13 INFO - Return code: 0 14:43:13 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 14:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:13 INFO - https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:43:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:43:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:43:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:43:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:43:32 INFO - Downloaded 18267589 bytes. 14:43:32 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'] 14:43:32 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 14:43:36 INFO - Return code: 0 14:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:36 INFO - https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:43:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:43:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:43:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:43:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:43:37 INFO - Downloaded 10840194 bytes. 14:43:37 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'] 14:43:37 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 14:43:37 INFO - Return code: 0 14:43:37 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')]} 14:43:37 INFO - https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:43:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:43:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:43:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:43:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTj8EiRNTQudEJ5oo0NLVw/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 14:43:40 INFO - Downloaded 109985713 bytes. 14:43:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:43:40 INFO - mkdir: /builds/slave/test/properties 14:43:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:43:40 INFO - Writing to file /builds/slave/test/properties/build_url 14:43:40 INFO - Contents: 14:43:40 INFO - build_url:https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:43:41 INFO - Running post-action listener: _resource_record_post_action 14:43:41 INFO - Running post-action listener: set_extra_try_arguments 14:43:41 INFO - ##### 14:43:41 INFO - ##### Finished download-and-extract step (success) 14:43:41 INFO - ##### 14:43:41 INFO - ##### 14:43:41 INFO - ##### Running populate-webroot step. 14:43:41 INFO - ##### 14:43:41 INFO - Running pre-action listener: _resource_record_pre_action 14:43:41 INFO - Running main action method: populate_webroot 14:43:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:43:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:43:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:43:41 INFO - '--tppagecycles', 14:43:41 INFO - '7'], 14:43:41 INFO - 'tests': ['tsvgm']}}, 14:43:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:43:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:43:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:43:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:43:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:41 INFO - 'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:43:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:43:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:41 INFO - 'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['damp', 'tps']}, 14:43:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:41 INFO - 'tests': ['damp', 'tps']}, 14:43:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['dromaeo_dom']}, 14:43:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:43:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['a11yr', 14:43:41 INFO - 'ts_paint', 14:43:41 INFO - 'tpaint', 14:43:41 INFO - 'sessionrestore', 14:43:41 INFO - 'sessionrestore_no_auto_restore', 14:43:41 INFO - 'tabpaint']}, 14:43:41 INFO - 'other-e10s': {'tests': ['a11yr', 14:43:41 INFO - 'ts_paint', 14:43:41 INFO - 'tpaint', 14:43:41 INFO - 'sessionrestore', 14:43:41 INFO - 'sessionrestore_no_auto_restore', 14:43:41 INFO - 'tabpaint']}, 14:43:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:43:41 INFO - 'ts_paint', 14:43:41 INFO - 'tpaint', 14:43:41 INFO - 'sessionrestore', 14:43:41 INFO - 'sessionrestore_no_auto_restore', 14:43:41 INFO - 'tabpaint']}, 14:43:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:43:41 INFO - 'ts_paint', 14:43:41 INFO - 'tpaint', 14:43:41 INFO - 'sessionrestore', 14:43:41 INFO - 'sessionrestore_no_auto_restore', 14:43:41 INFO - 'tabpaint']}, 14:43:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['a11yr', 14:43:41 INFO - 'ts_paint', 14:43:41 INFO - 'tpaint', 14:43:41 INFO - 'sessionrestore', 14:43:41 INFO - 'sessionrestore_no_auto_restore', 14:43:41 INFO - 'tabpaint']}, 14:43:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['a11yr', 14:43:41 INFO - 'ts_paint', 14:43:41 INFO - 'tpaint', 14:43:41 INFO - 'sessionrestore', 14:43:41 INFO - 'sessionrestore_no_auto_restore', 14:43:41 INFO - 'tabpaint']}, 14:43:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['tsvgx', 14:43:41 INFO - 'tsvgr_opacity', 14:43:41 INFO - 'tart', 14:43:41 INFO - 'tscrollx', 14:43:41 INFO - 'cart']}, 14:43:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:43:41 INFO - 'tsvgr_opacity', 14:43:41 INFO - 'tart', 14:43:41 INFO - 'tscrollx', 14:43:41 INFO - 'cart']}, 14:43:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:41 INFO - 'talos_options': ['--disable-e10s'], 14:43:41 INFO - 'tests': ['tp5o']}, 14:43:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:41 INFO - 'tests': ['tp5o']}, 14:43:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:41 INFO - 'talos_options': ['--disable-e10s', 14:43:41 INFO - '--xperf_path', 14:43:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:43:41 INFO - 'tests': ['tp5n']}, 14:43:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:41 INFO - 'talos_options': ['--xperf_path', 14:43:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:43:41 INFO - 'tests': ['tp5n']}}, 14:43:41 INFO - 'talos.zip': {'path': '', 14:43:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:43:41 INFO - Running post-action listener: _resource_record_post_action 14:43:41 INFO - ##### 14:43:41 INFO - ##### Finished populate-webroot step (success) 14:43:41 INFO - ##### 14:43:41 INFO - ##### 14:43:41 INFO - ##### Running create-virtualenv step. 14:43:41 INFO - ##### 14:43:41 INFO - Running pre-action listener: _resource_record_pre_action 14:43:41 INFO - Running main action method: create_virtualenv 14:43:41 INFO - Creating virtualenv /builds/slave/test/build/venv 14:43:41 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 14:43:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:43:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:43:41 INFO - Using real prefix '/tools/python27' 14:43:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:43:42 INFO - Installing distribute.............................................................................................................................................................................................done. 14:43:45 INFO - Installing pip.................done. 14:43:45 INFO - Return code: 0 14:43:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:43:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:43:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:43:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:45 INFO - 'HOME': '/Users/cltbld', 14:43:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:45 INFO - 'LOGNAME': 'cltbld', 14:43:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:45 INFO - 'MOZ_NO_REMOTE': '1', 14:43:45 INFO - 'NO_EM_RESTART': '1', 14:43:45 INFO - 'PAGER': '/bin/cat', 14:43:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:45 INFO - 'PWD': '/builds/slave/test', 14:43:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:45 INFO - 'SHELL': '/bin/bash', 14:43:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:43:45 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:43:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:45 INFO - 'USER': 'cltbld', 14:43:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:45 INFO - 'XPC_FLAGS': '0x0', 14:43:45 INFO - 'XPC_SERVICE_NAME': '0', 14:43:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:45 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 14:43:45 INFO - Cleaning up... 14:43:45 INFO - Return code: 0 14:43:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:43:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:43:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:43:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:45 INFO - 'HOME': '/Users/cltbld', 14:43:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:45 INFO - 'LOGNAME': 'cltbld', 14:43:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:45 INFO - 'MOZ_NO_REMOTE': '1', 14:43:45 INFO - 'NO_EM_RESTART': '1', 14:43:45 INFO - 'PAGER': '/bin/cat', 14:43:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:45 INFO - 'PWD': '/builds/slave/test', 14:43:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:45 INFO - 'SHELL': '/bin/bash', 14:43:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:43:45 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:43:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:45 INFO - 'USER': 'cltbld', 14:43:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:45 INFO - 'XPC_FLAGS': '0x0', 14:43:45 INFO - 'XPC_SERVICE_NAME': '0', 14:43:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:46 INFO - Downloading/unpacking psutil>=3.1.1 14:43:46 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 14:43:46 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 14:43:46 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 14:43:46 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 14:43:46 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 14:43:46 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 14:43:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:43:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:43:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:43:49 INFO - Installing collected packages: psutil 14:43:49 INFO - Running setup.py install for psutil 14:43:49 INFO - building 'psutil._psutil_osx' extension 14:43:49 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 14:43:49 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 14:43:49 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 14:43:49 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 14:43:49 INFO - building 'psutil._psutil_posix' extension 14:43:49 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 14:43:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:43:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:43:49 INFO - ^ 14:43:49 INFO - 1 warning generated. 14:43:49 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 14:43:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:43:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:43:49 INFO - Successfully installed psutil 14:43:49 INFO - Cleaning up... 14:43:50 INFO - Return code: 0 14:43:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:43:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:43:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:43:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:43:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:43:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:50 INFO - 'HOME': '/Users/cltbld', 14:43:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:50 INFO - 'LOGNAME': 'cltbld', 14:43:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:50 INFO - 'MOZ_NO_REMOTE': '1', 14:43:50 INFO - 'NO_EM_RESTART': '1', 14:43:50 INFO - 'PAGER': '/bin/cat', 14:43:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:50 INFO - 'PWD': '/builds/slave/test', 14:43:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:50 INFO - 'SHELL': '/bin/bash', 14:43:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:43:50 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:43:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:50 INFO - 'USER': 'cltbld', 14:43:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:50 INFO - 'XPC_FLAGS': '0x0', 14:43:50 INFO - 'XPC_SERVICE_NAME': '0', 14:43:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:43:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:43:52 INFO - Installing collected packages: mozsystemmonitor 14:43:52 INFO - Running setup.py install for mozsystemmonitor 14:43:52 INFO - Successfully installed mozsystemmonitor 14:43:52 INFO - Cleaning up... 14:43:53 INFO - Return code: 0 14:43:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:53 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 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:43:53 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 14:43:53 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 14:43:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:43:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:53 INFO - 'HOME': '/Users/cltbld', 14:43:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:53 INFO - 'LOGNAME': 'cltbld', 14:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:53 INFO - 'MOZ_NO_REMOTE': '1', 14:43:53 INFO - 'NO_EM_RESTART': '1', 14:43:53 INFO - 'PAGER': '/bin/cat', 14:43:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:53 INFO - 'PWD': '/builds/slave/test', 14:43:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:53 INFO - 'SHELL': '/bin/bash', 14:43:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:43:53 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:43:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:53 INFO - 'USER': 'cltbld', 14:43:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:53 INFO - 'XPC_FLAGS': '0x0', 14:43:53 INFO - 'XPC_SERVICE_NAME': '0', 14:43:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:53 INFO - Downloading/unpacking blobuploader==1.2.4 14:43:53 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 14:43:53 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 14:43:53 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 14:43:53 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 14:43:53 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 14:43:53 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 14:43:55 INFO - Downloading blobuploader-1.2.4.tar.gz 14:43:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:43:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:43:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:43:56 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 14:43:56 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 14:43:56 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 14:43:56 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 14:43:56 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 14:43:56 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 14:43:56 INFO - Downloading docopt-0.6.1.tar.gz 14:43:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:43:57 INFO - Installing collected packages: blobuploader, requests, docopt 14:43:57 INFO - Running setup.py install for blobuploader 14:43:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:43:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:43:57 INFO - Running setup.py install for requests 14:43:57 INFO - Running setup.py install for docopt 14:43:57 INFO - Successfully installed blobuploader requests docopt 14:43:57 INFO - Cleaning up... 14:43:57 INFO - Return code: 0 14:43:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:57 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 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:43:57 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 14:43:57 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 14:43:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:43:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:57 INFO - 'HOME': '/Users/cltbld', 14:43:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:57 INFO - 'LOGNAME': 'cltbld', 14:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:57 INFO - 'MOZ_NO_REMOTE': '1', 14:43:57 INFO - 'NO_EM_RESTART': '1', 14:43:57 INFO - 'PAGER': '/bin/cat', 14:43:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:57 INFO - 'PWD': '/builds/slave/test', 14:43:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:57 INFO - 'SHELL': '/bin/bash', 14:43:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:43:57 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:43:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:57 INFO - 'USER': 'cltbld', 14:43:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:57 INFO - 'XPC_FLAGS': '0x0', 14:43:57 INFO - 'XPC_SERVICE_NAME': '0', 14:43:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-lP7EGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-FhOjjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-XgN6v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-YvnaTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-z8KFLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-vVT3bO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-OeffbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-3sy8zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:43:58 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-x1x1lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:43:59 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-fjOUDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:43:59 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-oajpEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:43:59 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-6xJgOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:43:59 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-OTj_Yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:43:59 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-HD0U2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:43:59 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-v6n1QG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:43:59 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ldDBJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:43:59 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-2qxNOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:43:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:43:59 INFO - Running setup.py install for manifestparser 14:44:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:44:00 INFO - Running setup.py install for mozcrash 14:44:00 INFO - Running setup.py install for mozdebug 14:44:00 INFO - Running setup.py install for mozdevice 14:44:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:44:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:44:00 INFO - Running setup.py install for mozfile 14:44:00 INFO - Running setup.py install for mozhttpd 14:44:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:44:00 INFO - Running setup.py install for mozinfo 14:44:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:44:00 INFO - Running setup.py install for mozInstall 14:44:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Running setup.py install for mozleak 14:44:01 INFO - Running setup.py install for mozlog 14:44:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Running setup.py install for moznetwork 14:44:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Running setup.py install for mozprocess 14:44:01 INFO - Running setup.py install for mozprofile 14:44:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:44:01 INFO - Running setup.py install for mozrunner 14:44:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:44:02 INFO - Running setup.py install for mozscreenshot 14:44:02 INFO - Running setup.py install for moztest 14:44:02 INFO - Running setup.py install for mozversion 14:44:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:44:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:44:02 INFO - Cleaning up... 14:44:02 INFO - Return code: 0 14:44:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:44: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')]} 14:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44: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')]} 14:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:02 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 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:44:02 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 14:44:02 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 14:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:02 INFO - 'HOME': '/Users/cltbld', 14:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:02 INFO - 'LOGNAME': 'cltbld', 14:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:02 INFO - 'MOZ_NO_REMOTE': '1', 14:44:02 INFO - 'NO_EM_RESTART': '1', 14:44:02 INFO - 'PAGER': '/bin/cat', 14:44:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:02 INFO - 'PWD': '/builds/slave/test', 14:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:02 INFO - 'SHELL': '/bin/bash', 14:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:44:02 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:02 INFO - 'USER': 'cltbld', 14:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:02 INFO - 'XPC_FLAGS': '0x0', 14:44:02 INFO - 'XPC_SERVICE_NAME': '0', 14:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:44:02 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-55kK0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:44:02 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)) 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:44:02 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-LzJjE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:44:02 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)) 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:44:02 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-z8gQAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-vIfQlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-PI3wGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-OBn75A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Mg3ajq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-UrFPpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-9ZHsBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-2La5iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-DEd09o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:44:03 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)) 14:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:44:03 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-HQbAmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:44:04 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-kuFqET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:44:04 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-GgaL3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:44:04 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-BJKmh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:44:04 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-TtSO9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:44:04 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-tkCOJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:44:04 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)) 14:44:04 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)) 14:44:04 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)) 14:44:04 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)) 14:44:04 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)) 14:44:04 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)) 14:44:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44:06 INFO - Downloading blessings-1.6.tar.gz 14:44:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:44:07 INFO - Installing collected packages: blessings 14:44:07 INFO - Running setup.py install for blessings 14:44:07 INFO - Successfully installed blessings 14:44:07 INFO - Cleaning up... 14:44:07 INFO - Return code: 0 14:44:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:44:07 INFO - Reading from file tmpfile_stdout 14:44:07 INFO - Current package versions: 14:44:07 INFO - blessings == 1.6 14:44:07 INFO - blobuploader == 1.2.4 14:44:07 INFO - docopt == 0.6.1 14:44:07 INFO - manifestparser == 1.1 14:44:07 INFO - mozInstall == 1.12 14:44:07 INFO - mozcrash == 0.17 14:44:07 INFO - mozdebug == 0.1 14:44:07 INFO - mozdevice == 0.48 14:44:07 INFO - mozfile == 1.2 14:44:07 INFO - mozhttpd == 0.7 14:44:07 INFO - mozinfo == 0.9 14:44:07 INFO - mozleak == 0.1 14:44:07 INFO - mozlog == 3.2 14:44:07 INFO - moznetwork == 0.27 14:44:07 INFO - mozprocess == 0.22 14:44:07 INFO - mozprofile == 0.28 14:44:07 INFO - mozrunner == 6.11 14:44:07 INFO - mozscreenshot == 0.1 14:44:07 INFO - mozsystemmonitor == 0.3 14:44:07 INFO - moztest == 0.7 14:44:07 INFO - mozversion == 1.4 14:44:07 INFO - psutil == 3.1.1 14:44:07 INFO - requests == 1.2.3 14:44:07 INFO - wsgiref == 0.1.2 14:44:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:07 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 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:44:07 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 14:44:07 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 14:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:07 INFO - 'HOME': '/Users/cltbld', 14:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:07 INFO - 'LOGNAME': 'cltbld', 14:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:07 INFO - 'MOZ_NO_REMOTE': '1', 14:44:07 INFO - 'NO_EM_RESTART': '1', 14:44:07 INFO - 'PAGER': '/bin/cat', 14:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:07 INFO - 'PWD': '/builds/slave/test', 14:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:07 INFO - 'SHELL': '/bin/bash', 14:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:44:07 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:07 INFO - 'USER': 'cltbld', 14:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:07 INFO - 'XPC_FLAGS': '0x0', 14:44:07 INFO - 'XPC_SERVICE_NAME': '0', 14:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 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)) 14:44:07 INFO - Cleaning up... 14:44:07 INFO - Return code: 0 14:44:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:07 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 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:44:07 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 14:44:07 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 14:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:07 INFO - 'HOME': '/Users/cltbld', 14:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:07 INFO - 'LOGNAME': 'cltbld', 14:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:07 INFO - 'MOZ_NO_REMOTE': '1', 14:44:07 INFO - 'NO_EM_RESTART': '1', 14:44:07 INFO - 'PAGER': '/bin/cat', 14:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:07 INFO - 'PWD': '/builds/slave/test', 14:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:07 INFO - 'SHELL': '/bin/bash', 14:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:44:07 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:07 INFO - 'USER': 'cltbld', 14:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:07 INFO - 'XPC_FLAGS': '0x0', 14:44:07 INFO - 'XPC_SERVICE_NAME': '0', 14:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:08 INFO - Downloading/unpacking jsonschema 14:44:08 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 14:44:08 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 14:44:08 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 14:44:08 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 14:44:08 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 14:44:08 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 14:44:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:44:10 INFO - Downloading/unpacking functools32 (from jsonschema) 14:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:10 INFO - Downloading functools32-3.2.3-2.tar.gz 14:44:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:44:11 INFO - warning: no files found matching '*.txt' 14:44:11 INFO - no previously-included directories found matching 'build' 14:44:11 INFO - no previously-included directories found matching 'dist' 14:44:11 INFO - no previously-included directories found matching '.git*' 14:44:11 INFO - Installing collected packages: jsonschema, functools32 14:44:11 INFO - Running setup.py install for functools32 14:44:11 INFO - warning: no files found matching '*.txt' 14:44:11 INFO - no previously-included directories found matching 'build' 14:44:11 INFO - no previously-included directories found matching 'dist' 14:44:11 INFO - no previously-included directories found matching '.git*' 14:44:11 INFO - Successfully installed jsonschema functools32 14:44:11 INFO - Cleaning up... 14:44:11 INFO - Return code: 0 14:44:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:44: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')]} 14:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44: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')]} 14:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102805a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde1bc910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, '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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 14:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:11 INFO - 'HOME': '/Users/cltbld', 14:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:11 INFO - 'LOGNAME': 'cltbld', 14:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:11 INFO - 'MOZ_NO_REMOTE': '1', 14:44:11 INFO - 'NO_EM_RESTART': '1', 14:44:11 INFO - 'PAGER': '/bin/cat', 14:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:11 INFO - 'PWD': '/builds/slave/test', 14:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:11 INFO - 'SHELL': '/bin/bash', 14:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:44:11 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:11 INFO - 'USER': 'cltbld', 14:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:11 INFO - 'XPC_FLAGS': '0x0', 14:44:11 INFO - 'XPC_SERVICE_NAME': '0', 14:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:11 INFO - Downloading/unpacking flake8 14:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:44:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:44:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:44:14 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 14:44:14 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 14:44:14 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 14:44:14 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 14:44:14 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 14:44:14 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 14:44:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:44:14 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 14:44:14 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 14:44:14 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 14:44:14 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 14:44:14 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 14:44:14 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 14:44:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:44:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:44:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:44:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:44:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:44:15 INFO - no previously-included directories found matching 'docs/_build' 14:44:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:44:15 INFO - Running setup.py install for pep8 14:44:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:44:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:44:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:44:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:44:15 INFO - no previously-included directories found matching 'docs/_build' 14:44:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:44:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:44:15 INFO - Cleaning up... 14:44:15 INFO - Return code: 0 14:44:15 INFO - Running post-action listener: _resource_record_post_action 14:44:15 INFO - Running post-action listener: _start_resource_monitoring 14:44:15 INFO - Starting resource monitoring. 14:44:15 INFO - ##### 14:44:15 INFO - ##### Finished create-virtualenv step (success) 14:44:15 INFO - ##### 14:44:15 INFO - ##### 14:44:15 INFO - ##### Running install step. 14:44:15 INFO - ##### 14:44:15 INFO - Running pre-action listener: _resource_record_pre_action 14:44:15 INFO - Running main action method: install 14:44:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:44:15 INFO - Reading from file tmpfile_stdout 14:44:15 INFO - Detecting whether we're running mozinstall >=1.0... 14:44:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:44:15 INFO - Reading from file tmpfile_stdout 14:44:15 INFO - Output received: 14:44:15 INFO - Usage: mozinstall [options] installer 14:44:15 INFO - Options: 14:44:15 INFO - -h, --help show this help message and exit 14:44:15 INFO - -d DEST, --destination=DEST 14:44:15 INFO - Directory to install application into. [default: 14:44:15 INFO - "/builds/slave/test"] 14:44:15 INFO - --app=APP Application being installed. [default: firefox] 14:44:15 INFO - mkdir: /builds/slave/test/build/application 14:44:15 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'] 14:44:15 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 14:44:49 INFO - Reading from file tmpfile_stdout 14:44:49 INFO - Output received: 14:44:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:44:49 INFO - Running post-action listener: _resource_record_post_action 14:44:49 INFO - ##### 14:44:49 INFO - ##### Finished install step (success) 14:44:49 INFO - ##### 14:44:49 INFO - ##### 14:44:49 INFO - ##### Running run-tests step. 14:44:49 INFO - ##### 14:44:49 INFO - Running pre-action listener: _resource_record_pre_action 14:44:49 INFO - Running main action method: run_tests 14:44:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:44:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:44:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:44:49 INFO - 2016-05-14 14:44:49.561 screenresolution[1946:14584] starting screenresolution argv=screenresolution get 14:44:49 INFO - 2016-05-14 14:44:49.563 screenresolution[1946:14584] Display 0: 1600x1200x32@60 14:44:49 INFO - 2016-05-14 14:44:49.568 screenresolution[1947:14586] starting screenresolution argv=screenresolution list 14:44:49 INFO - Available Modes on Display 0 14:44:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:44:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:44:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:44:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:44:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:44:49 INFO - Intel Iris: 14:44:49 INFO - Chipset Model: Intel Iris 14:44:49 INFO - Type: GPU 14:44:49 INFO - Bus: Built-In 14:44:49 INFO - VRAM (Dynamic, Max): 1536 MB 14:44:49 INFO - Vendor: Intel (0x8086) 14:44:49 INFO - Device ID: 0x0a2e 14:44:49 INFO - Revision ID: 0x0009 14:44:49 INFO - Displays: 14:44:49 INFO - Display: 14:44:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:44:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:44:49 INFO - Display Serial Number: 1600x1200 60 14:44:49 INFO - Main Display: Yes 14:44:49 INFO - Mirror: Off 14:44:49 INFO - Online: Yes 14:44:49 INFO - Rotation: Supported 14:44:49 INFO - Return code: 0 14:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:44:49 INFO - Python 2.7.3 14:44:49 INFO - Return code: 0 14:44:49 INFO - grabbing minidump binary from tooltool 14:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:49 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 0x1026a9d30>, '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 0x1024cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026a0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:44:49 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 14:44:49 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 14:44:49 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 14:44:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:44:49 INFO - Return code: 0 14:44:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:44:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:44:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:44:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:44:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:44:51 INFO - Return code: 0 14:44:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:44:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rUpyyyAK1j/Render', 14:44:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:51 INFO - 'HOME': '/Users/cltbld', 14:44:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:51 INFO - 'LOGNAME': 'cltbld', 14:44:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:44:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:51 INFO - 'MOZ_NO_REMOTE': '1', 14:44:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:44:51 INFO - 'NO_EM_RESTART': '1', 14:44:51 INFO - 'PAGER': '/bin/cat', 14:44:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:51 INFO - 'PWD': '/builds/slave/test', 14:44:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:44:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:51 INFO - 'SHELL': '/bin/bash', 14:44:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vy0Lu4ASN4/Listeners', 14:44:51 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 14:44:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:51 INFO - 'USER': 'cltbld', 14:44:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:51 INFO - 'XPC_FLAGS': '0x0', 14:44:51 INFO - 'XPC_SERVICE_NAME': '0', 14:44:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:44:51 INFO - mozversion application_buildid: 20160514130527 14:44:51 INFO - mozversion application_changeset: 403912ca555eb65f814b18ecf38ad8e8e98569f5 14:44:51 INFO - mozversion application_display_name: Nightly 14:44:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:44:51 INFO - mozversion application_name: Firefox 14:44:51 INFO - mozversion application_remotingname: firefox 14:44:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:44:51 INFO - mozversion application_vendor: Mozilla 14:44:51 INFO - mozversion application_version: 49.0a1 14:44:51 INFO - mozversion platform_buildid: 20160514130527 14:44:51 INFO - mozversion platform_changeset: 403912ca555eb65f814b18ecf38ad8e8e98569f5 14:44:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:44:51 INFO - mozversion platform_version: 49.0a1 14:44:51 INFO - using testdate: 1463262291 14:44:51 INFO - actual date: 1463262291 14:44:51 INFO - starting webserver on 'localhost:52195' 14:44:51 INFO - SUITE-START | Running 2 tests 14:44:51 INFO - TEST-START | dromaeo_css 14:44:51 INFO - operating with platform_type : mac_ 14:44:51 INFO - Initialising browser for dromaeo_css test... 14:44:51 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpXabD0b/profile http://localhost:52195/getInfo.html) 14:44:53 INFO - PROCESS | 1965 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 14:44:53 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 14:44:53 INFO - PROCESS | 1965 | colorDepth:24 14:44:53 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 14:44:53 INFO - PROCESS | 1965 | __metrics 14:44:53 INFO - PROCESS | 1965 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:44:53 ERROR - PROCESS | 1965 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:44:54 INFO - TEST-INFO | 1965: exit 0 14:44:54 INFO - Browser initialized. 14:44:54 INFO - Running cycle 1/1 for dromaeo_css test... 14:44:54 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpXabD0b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:45:06 INFO - PROCESS | 1967 | RSS: Main: 239763456 14:45:06 INFO - PROCESS | 1967 | 14:45:57 INFO - PROCESS | 1967 | 1463262357655 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] 14:48:08 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52195/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52195/tests/dromaeo/cssquery-ext.html) 14:48:09 INFO - PROCESS | 1967 | RSS: Main: 374796288 14:48:09 INFO - PROCESS | 1967 | 14:51:12 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52195/tests/dromaeo/cssquery-ext.html (next: http://localhost:52195/tests/dromaeo/cssquery-jquery.html) 14:51:13 INFO - PROCESS | 1967 | RSS: Main: 373063680 14:51:13 INFO - PROCESS | 1967 | 14:54:15 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52195/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52195/tests/dromaeo/cssquery-mootools.html) 14:54:16 INFO - PROCESS | 1967 | RSS: Main: 366383104 14:54:16 INFO - PROCESS | 1967 | 14:57:18 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52195/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52195/tests/dromaeo/cssquery-prototype.html) 14:57:19 INFO - PROCESS | 1967 | RSS: Main: 366141440 14:57:19 INFO - PROCESS | 1967 | 15:00:22 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52195/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52195/tests/dromaeo/cssquery-yui.html) 15:00:23 INFO - PROCESS | 1967 | RSS: Main: 337686528 15:00:23 INFO - PROCESS | 1967 | 15:03:25 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52195/tests/dromaeo/cssquery-yui.html (next: http://localhost:52195/tests/dromaeo/cssquery-dojo.html) 15:03:26 INFO - PROCESS | 1967 | RSS: Main: 380178432 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - PROCESS | 1967 | __start_tp_report 15:03:26 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 15:03:26 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 15:03:26 INFO - PROCESS | 1967 | |i|pagename|runs| 15:03:26 INFO - PROCESS | 1967 | |0;dojo.html;3242.5784647995756;3450.2581944881863;3462.7576069675133;3484.1638006878356;3509.8069606171666;5613.887722245553;5635.126555383922;5649.6327738696955;5712.828615141547;5782.53739700824;5666.065099258628;5718.6568805871675;5723.685197314146;5728.112142617891;5757.539396848252;5176.456472070426;5197.870053248661;5222.060029194743;5239.869003274911;5331.2269720890445;5222.294990176316;5245.580353571724;5283.996040752264;5315.468453154692;5324.8019195681045;4526.3210518422175;5185.351831021122;5206.167013277876;5238;5269.341332333458;4636.188666983281;4793.664443488957;5151.6136289778415;5301.655392399482;5312.5218730314455;5092;5253.86865328366;5265.447128051538;5273.31446911899;5295.364556253236;5586.497215250647;5615.7472913718575;5735.885282294373;5744.626599271034;5751.424857514257;6059.518647548154;6210.410011048988;6222.688865556749;6224.782132625336;6262.405071518198;6094.756209751605;6103.1881284819;6155.44600985909;6180.907286390708;6208.1308616793685;5728.341240757366;5733.627314224562;5784.595078344518;5797.681127537984;5810.767569297265;6245.718942647592;6252.718627661766;6265.718042687999;6266.404691554295;6273.749050037947;6026.186464827279;6133.110698948627;6150.692465376714;6168.722407491674;6178.073289006595;6231.813045608639;6245.843853903689;6249.062640603855;6259.2488901332135;6278.748850046039;6106.603070800384;6161.784337548164;6186.226721659793;6196.349383314777;6214.751409943642;6123.892747182667;6134.662593557308;6163.506919446433;6182.598131121463;6209.316975132731;4331.566843315643;4824.300476430898;4909.582685471775;4961.181405068123;4964;4337.82648694055;4388.990532177617;4423.60187583119;4431.423914891108;4456.7548784817145;5602.299712535933;5643.604947653707;5692.345380281238;5702.714864256771;5706.3722990471015;6138.878900475516;6157.1072194532435;6178.382161783786;6198.504119670415;6233.345498722569;5694.715264236855;5700.3444603871085;5703.8859222816145;5708.086706127;5713.114467257498;5370.731463426813;5406.297181366476;5415.972920135374;5423.078076726966;5432.266644003009;6206.441420272107;6221.688915554295;6245.437910588069;6254.437100660963;6337.176167098432;6247.5001999840815;6250.249970003629;6273.62358258506;6281.152044473937;6293.465055470337;6151.661658608819;6260.123582698335;6310.7475700971445;6332.366763323631;6340.334264902303;6103.694815259308;6111.449969502766;6138.081874381997;6155.599886007395;6205.193324867739;6250.968745156245;6261.279952805486;6263.467605253604;6266.905996409966;6282.434580887742;5868.911966320594;5926.644401335933;6081.878362432815;6090.116933044772;6107.69461526913;4822.373091498082;6137.974468425319;6142.1132067928;6147.907781383372;6150.661713605616;5990.550708696778;6000.849978750566;6005.699715014319;6051.969740151271;6068.059450785136;5891.351951285441;6047.304559975659;6094.177286066324;6147.815565533041;6149.631022138686;5998.040313549811;6013.819585412444;6019.157317975574;6031.4270144336215;6062.545309101747 15:03:26 INFO - PROCESS | 1967 | |1;ext.html;496.30517275813855;523.2909407752493;526.7629566694986;530.2073400266602;536.0940011380766;31008;32648.183795405126;32695.67304326956;43198.92002699934;44585;45396.54603453964;45806.7709661452;45831.31253031199;45932.311015334795;46003;9619.230461563058;9797.510124493783;9801.656942007032;9803.901960980405;9817.263705222136;8745.431546949429;8925.55372231389;9552.904470955305;11517.827232591519;11737.178397512178;11742.471588778526;11812;11816.409179540988;11826.645200643994;11831.940840295787;8738.344624153215;10137.847932281022;11780;11899.34553599556;11919.463624136848;11398.772024559463;11695.239809412446;11705.414729263588;11850.525978960832;11865.703357416047;41736.79131604323;42117;42119.36820947688;42179.789101054295;42193.36709949353;3126.1559378967777;3293.5389045533643;3323.9501407478906;3594.784312941206;4115.176964607091;28576.714232857612;29700.554491682644;30195.396092078034;30445.847770761004;30747.385052299054;2715.592661100831;2717.2255907066587;2733.5489644208683;2762.8342299461897;2859.8713057912446;512490;520523.39738301444;522892.771072292;523132;523688;67444.6627766858;68006;68056;68092.659536702;68143.63712725717;70617;70849.64575177194;70923.64538177276;70975.64512177405;70987.2901270991;16824.663506729798;17273.827261727474;17301;17335.21991510385;17370.739438908426;11888.583899563473;12899.871521198897;13064.346782660828;13138;13140.474381024653;19582.754380879167;20146.597068058556;20202.989850507416;20211.393658190278;20241.493962651053;7018.017342629362;8358.247757701836;8437.451565648213;8457.915420845713;8518.78703032429;1003.2525768302661;1015.9586869924465;1033.5452400943561;1107.307932542161;1185.4191446191305;26945.595816062774;29666.961656341926;29805;30253.243668908453;30481.237969050508;1011.5751384418562;1076.0333948256778;1130.745582244005;1200.6458094862037;1298.727267273864;12761.971199362237;13486.797698034537;15495.225238738018;15831.52505424816;15927.442539511061;695450;704620;705736;706070;707860;61789;63049.68475157595;63240.683796581645;63263.68368158222;63351;1330.9001824863174;1364.6179069860257;1366.583192126411;1492.1793013842343;1515.2348064227494;25691.743082569683;31010.68989310078;31026.844865775525;31591.366992795203;31644.208894777814;53407.93184136373;53960.460395395545;54054.729726351114;54079.45920540902;54159.18761218506;559747;580487;580505;582055;583638;22917.54164916659;24091.397715056562;24598.631020535064;24612.6308105375;24982;35164.71500427509;39012;40517.59482405256;45813.854653632596;46805.76597117129;30630.387392252476;37943.81028094842;38358.61641383662;38980.41529377005;40125;37387.813060934524;37710.81144594259;37958.810205948794;38488.6151138485;39086.804565976985;32263.032109036765;37817.432738509495;38805.6119438802;39114.41328380133;39117.82646520609;31934.68065319317;33155;34035;34135.487967681;34166.487502686985;24870.00519979253;24985.125520606693;25101.74898251067;25731.871340643178;26117.216483505526 15:03:26 INFO - PROCESS | 1967 | |2;jquery.html;353.52274429520133;380.4939430557359;394.68819632490323;396.8749843799204;424.1157187264555;18014.279428822847;20803.16787328509;21436.464088397777;21440.892795536038;21565.892170539144;12102.81845772314;12119.394030298494;12292.078094142953;12437.564685235995;12449.253044817317;5365.953639040389;5525.143602741561;5525.806596769114;5580.218769372291;5591.02157122504;3928.057266256092;4059.4722686050773;4192.24539582875;4194.328907374821;5870.794522191724;6178.814635560941;6231.968840155793;6234.875302493947;6242.875142497147;6308.495320374359;6488.740450381979;6648.634325112117;6658.201015878126;6890.689918953634;6898.275681053492;5314.176302673091;5671.234383358235;5680.772769089232;5898.675572843492;5947.316058653267;13391.92864570832;13773.449062037607;13834.723305533833;14026.649333766636;14051.508197213048;1854.1656254685445;2080.78151794061;2112.9894350528148;2141.3468891987936;2308.1690591387087;9798.902010979942;9888.802223955481;10235.181185505216;10322.845157322647;10371.688849334532;1569.2467615544488;1680.7142785726455;1715.5368050626262;1723.7069698151345;1745.8341457561512;78279;112918.43540782326;118257;118477;119356;11537.88462115385;11601.12991525639;11617.6514704559;11862.584809531625;11878.227915185658;13436;15309.00491468051;15359.078455292718;15470.381184752709;15503.457378991681;8624.482531048157;8893.4663920165;8922.955385223162;8938.374313798102;9036.819263614823;7348.52234604749;8821.470711757314;8847.557622118868;8941.821163576693;8964.731058068268;4211.1998720243055;4597.264437689953;4653.302004699322;4760.857174284777;4762.64280178988;1611.6454380036373;1807.394522834865;1848.1775609853755;1984.3947595983363;1990.8905010224287;914.6570036236411;967.274544091931;993.5181437003048;1023.6007956896816;1042.635077722791;15977.840221597635;20751.792482074987;21229.363119106143;21243.36269911905;21377.35867923965;970.4807927758616;981.249344251648;1020.9693709188737;1024.3393011507535;1036.6216331039116;10979.670609881716;11313.886861131283;11328.150388720715;11379;11422.600208992812;598655;599157.8337445897;610350;610950;616055;79594.6020269895;114385;142892;149140;153862;453.82754553269547;454.422882938666;471.8419329524579;479.36244794423334;481.5714014527074;11149.386783726974;11153.88846111561;11383.943080284545;11552.075833933433;11849.585264516045;51226.48773512217;53163;54734.72632636971;56653;57517.42482575288;66631;82489;85481.57259213664;86991;88599.11400885909;7961.402894782798;8033.799155021171;8437.620307086074;8714.346424018097;8871.689490867788;6572.401912393723;11052;12363.62909112728;12907.483700651868;13014.934925325313;10857.782844342912;11390.25963312382;11516.136289778133;11571.132165087483;11814.936655701029;10685.96966576348;11369.943150284196;11506.482208300815;11772.941135294268;11776.882231177578;9407.247420206504;9513.571889264862;11809.645710628694;11857.16999810005;11961.282323060645;8361.95819020901;8448.619812108593;8488.74533763988;8757.737267881974;8759.605817738344;8578.270846978077;10917.253239481963;11470.885291146982;11644.941775291069;11902.940485297519 15:03:26 INFO - PROCESS | 1967 | |3;mootools.html;1269.8031805070218;1485.227681605565;1603.9524902684893;1840.2087102545909;2000.9499762505927;6850.451963842893;8908.331875109368;9149.039350868146;9171.54142292886;9275.536223188847;8897.733068007954;9129.269658427309;9136.086391360861;9150;9166.266698664107;2134.9146034158616;2195.934121976335;2198.626233540294;2426.672399226104;2458.8032957363366;2224.3438185735167;2251.8423710340285;2392.9521409571885;2457.741937096606;2461.6676748638884;2419.891104900275;2423.260905423845;2426.5874801283826;2433.866137362453;2434.8782560871887;2381.7022872140983;2430.3924018995253;2433.3551608823677;2433.537627850703;2437.4515733959893;2164.7510536288214;2238.507528343762;2240.8879556022134;2242.1479837661586;2396.724376696685;8870.246029087535;9104.772380690469;9112.772180695469;9120.042395548504;9123.543822808899;5426.945730542723;5605.831825045255;5607.663540187602;5607.971960140214;5611.158326251054;3549.201429678302;3627.2564124354444;3757.102663155089;3762.9623703762886;3764.7552909061096;1012.266107072374;1034.3431920730316;1061.665575343772;1076.6500887211687;1080.8865069167703;12264.81602775959;12291.508339666403;12295.385230738517;12300.876991230152;12307;4902.681325713817;4932.1861892788;5053.393592768818;5057.266696329012;5073.11220536412;10367;10402.375857448576;10430.217733670004;10433.895661043443;10440.42577658221;5685.971570142123;5688.544916406678;5695.088785794336;5710;5712.428757124254;4606.56237657422;4955.826546070943;5018.89962200754;5169.974150129226;5170.922436163461;3776.848061341317;3864.053306939818;4096;4102.671786257092;4208.978955105266;1316.3089378076395;1343.2209318595192;1353.472145863114;1355.5323413422354;1367.2138520350838;799.552250739576;929.149827907457;935.9344845860725;967.7145242153582;983.1053741095711;5808.651480911159;5848.181254624356;5951.285845698544;6006.789762358297;6073.6659483727935;914.7804526913493;926.9814603707888;970.6845275285419;979.1725991537139;1027.660871912267;3471.3751524725285;4108.856190033334;4122.381642753551;4135.32519329672;4185.162967406531;10649.893501065046;10990.560377584807;11173.944130279458;11181.83227251592;11205.159613029055;11078.501467433985;11095.55617775296;11108.500117494732;11112.11103111749;11117.388543630179;7100.360967512948;8224.62989165489;8423.494590324477;8431.578421079044;8512.361572882059;6084.300305464752;6119.724612392364;6186.721597528212;6253.062040693932;6343.175387199634;9899.208063355058;10057.29598928068;10325.225608079274;10332.018458246455;10341.327813692085;10305.278630495794;10599.099076578268;10644.733881653021;10647.627333043307;10767.515461804394;7714.035745531809;7718.575478348745;7758.534487930742;7829.6085195741125;7870.921290787019;8399.748007559783;8443.240108390275;8472.110428405174;8486.363522735695;8486.660533578588;7396.482246242711;7581.848363032819;7590.392768578611;7720.691172353268;7728.690852365842;7385.076865391826;7459.962700186465;7461.738839140604;7650.464467487445;7663.846723065396;7081.82295442618;7139.286071392819;7144.178419481618;7144.857102858018;7152.249013853679;4801.4718380977365;4970.179920313108;5077.695338279715;5092;5099.847004589868;4850.811551169986;5028.321176641107;5043.672161309503;5113.15632920564;5318.441563635917 15:03:26 INFO - PROCESS | 1967 | |4;prototype.html;312.5124805303726;315.35195173917606;325.0443695535128;325.2162288883791;328.3646144709988;5064.366954130734;5364.275822763926;5436.700981446019;5479.917801232979;5504.284443022407;5126.48768613259;5458.4814442627885;5474.315710536183;5488.231647569343;5496.835094947148;3980.263651224518;4063.923060388999;4125.051238215212;4148.149629325996;4193.203291374629;3716.2381711749026;4197.307444271692;4217.725847819897;4235.68232382571;4241.554636763141;3889.2221555688834;4079.551249362568;4152.522459917103;4185.958140418603;4224.007358270804;3715.665590096877;3727.9375378017407;4166.91666166678;4181.205570941512;4187.790610469479;3078.676169247245;3347.6484969078383;3388.339273841602;3412.6178897546533;3701.2782507411066;4764.571188593009;4870.318155458239;5177.249298851645;5222.73886305687;5225.660332078441;4418.911621767546;4529.343245229456;4816.927746083811;4828.251620998751;4837.806487740486;4810.759462026885;4867.829625963074;5730.312362516524;5753.338366087912;5764.221830052973;3469.300042978922;4012.839486420569;4035.9192816143805;4071.3282308419075;4095.672346212296;4552.632631031878;5014.974925125388;5319.941033905481;5449.972750136264;6100.877982440371;2874.439484300583;3931.68546516278;4073;4091.447654566655;4108;3650.5984341722387;3725.4598083277774;4163.937540936889;4197.181549597799;4206.095689426788;4471.932921006188;4630.328602352707;4793.257045158006;4801.18379875422;4830.396200474941;5360.8659783504945;5370.355557333067;5645.068563687028;5733.254676891977;5809.7095145242565;1202.5370232460566;1352.48605529898;1365.8497565267812;1386.646405166676;1389.4650559534653;737.668508342447;806.6289506826926;814.267159556404;829.6225217526011;844.8425656850031;3954.0114971257185;4117.676439738323;4307.480124127421;4340.63270069927;4481.3726078349055;4724.622030237572;4741.928871066937;4747.382840230817;4907.509249075135;5065.898682026339;4042.4542686736913;4080.5511393746665;4518.876128219731;4559.931601025921;4566.725996440225;2233.218373569238;2240.80117137334;2339.82451316152;2507.796257796268;2580.20709556951;4732.905341893143;4846.655807492819;4969.726665033386;5026.045051440215;5181.274621552986;4314.633256173261;4459.996500787297;4470.530594287618;4737.957649317144;4805.087033463631;4517.435320584927;4620.168369693421;4778.593819525379;5243.8274843719;5270.183121616155;4442.533533979015;4459.2865141577195;4600.3329517219145;4973.776180071978;5027.271045698426;4807.245355445168;4911.189653707242;4926.137925862888;4954.662241194929;4978.30303757467;4503.572160644733;4832.815960089801;5215.791368345223;5231.398389185292;5275.788968441373;3147.6222853257755;3421.5894092709036;3450.1892055366466;3479.3041391722263;3499.720022398253;4485.865424037284;4858.5384388483035;4960.503949605155;5020.924686129784;5064;4258.850940217201;4628.560286772751;4841.491643377535;4863.060920654281;4874.610031197567;4317.956820431881;4339.7613131278085;4683.601893838925;4781.6891902026255;4808.639352048541;4198.517170525307;4593.966357569654;4691.519598783222;4752.714837109785;4757.976210118927;3786.8674596388996;4362.454693163355;4367.847125350598;4620;4832.323474713613;4304.579488768762;4325.805338759827;4744.7390393528685;4755.667103302736;4791.688540244873 15:03:26 INFO - PROCESS | 1967 | |5;yui.html;665.8202285382947;744.226004954847;749.5577609210565;752.1951511882285;770.5299767142039;14013.859861401383;17166.939967203936;17995.28018879245;18037.36869209578;18067.096645167756;15478.380864765395;16374.918125409356;16462.67074658506;16469.67060658789;16530.917345413258;929.0709290709308;947.2327414794039;957.5547370472729;957.6504575829807;958.611762236292;932.3333816321328;944.1596978688972;944.593824655398;946.5693109635134;947.1854205383365;991.3803872579638;991.8760154980628;992.9602815887356;995.437577768562;996.9601215951353;985.2265971212599;985.5170966226569;986.5264672956949;986.5708416838662;988.8763904511936;734.9816254593678;735.3933005270619;735.8417940142841;738.3133685672323;738.4129616954534;14977.400903963828;15309.157996310198;15325.46360877364;15340.386384544605;15346.616334591612;12416.379181041002;12417.379131043412;12552.497900083985;12659.367031648473;13044.543440979614;9890.554925028391;10244.129249013768;10259.743506412326;10307.587696492132;10550.208734344878;3377.611574668895;3456.948145777815;3482.4428091505492;3503.2643144939593;3547.592026916912;63536;66245.66877165632;66517.33482665208;84464;85996.14003859943;9175.908240917639;9423;9440.197583205367;9622.9037709622;9778;20507.58984820295;21144.471388215417;21170.788292116882;21171.78828211729;21536.13855445796;7713;7741.535507869546;7776.144624091346;8030.71892483772;8106.878396824052;3971.781552014609;4031.5766844481495;4085.6935729820384;4134.111166099303;4389.122175564899;3628.6734193922675;3737.663610275088;3738.102855314705;3738.2149748552565;3739.3082279778328;661.6063442251852;679.6678510120121;711.8469529051279;714.3677845107118;735.1729304532402;565.2341077839468;586.302300262686;594.1622312539299;606.139282219241;613.6747523812387;8204.179582041868;8535.274501667303;8651;8657.783555411039;8739.737807865775;509.53377659441935;561.3236050559067;605.8061420345447;623.5354660777749;632.7638789020889;3939.2515422069714;3967.563568007517;3993.880183594497;3999.420084087791;4065.207284579538;96593.51703241438;98356;98946;101835;101905;69185;86936.13063869407;87482;87554.12445875588;88537;79040.20959790329;80694.59652701698;81464.59267703624;81862;82367.17632823595;7764.526769855465;7775.261350171724;7878.960605196938;7918;7997.8400431992195;49104.50895491142;49414.50585494243;49430;50719.49280507147;51593.96812063813;52748;65729.67135164485;70147;71024.64487677529;71092;10871.782564348827;11095.833562496731;11110.722231944266;11177.441127943734;11274.154438416988;11157.832632510357;11225.66323010311;11279.774404512027;11314.886851131383;11330.660080197607;10592.682219533426;10665.253432259979;10671.893281067401;10940.106254263606;11052;10553.577856885639;10574.84137737919;10670.62652807148;10776.191785616267;10883.183761217782;10392.688219353431;10397.792044159225;10430.895691042992;10447.268691191848;10462.895371046496;8941.687040953535;8988.056568887218;9030.729078127666;9167.724968250963;9365.363573062876;8399.95800020996;8850.557472126497;8899.911000889908;8997.01032886369;9300.813983720423 15:03:26 INFO - PROCESS | 1967 | __end_tp_report 15:03:26 INFO - PROCESS | 1967 | __start_cc_report 15:03:26 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,1433 15:03:26 INFO - PROCESS | 1967 | __end_cc_report 15:03:26 INFO - PROCESS | 1967 | __startTimestamp1463263406144__endTimestamp 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - PROCESS | 1967 | ------- Summary: start ------- 15:03:26 INFO - PROCESS | 1967 | Number of tests: 6 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - PROCESS | 1967 | [#0] dojo.html Cycles:165 Average:5723.40 Median:6005.70 stddev:643.50 (10.7%) stddev-sans-first:615.34 15:03:26 INFO - PROCESS | 1967 | Values: 3242.6 3450.3 3462.8 3484.2 3509.8 5613.9 5635.1 5649.6 5712.8 5782.5 5666.1 5718.7 5723.7 5728.1 5757.5 5176.5 5197.9 5222.1 5239.9 5331.2 5222.3 5245.6 5284.0 5315.5 5324.8 4526.3 5185.4 5206.2 5238.0 5269.3 4636.2 4793.7 5151.6 5301.7 5312.5 5092.0 5253.9 5265.4 5273.3 5295.4 5586.5 5615.7 5735.9 5744.6 5751.4 6059.5 6210.4 6222.7 6224.8 6262.4 6094.8 6103.2 6155.4 6180.9 6208.1 5728.3 5733.6 5784.6 5797.7 5810.8 6245.7 6252.7 6265.7 6266.4 6273.7 6026.2 6133.1 6150.7 6168.7 6178.1 6231.8 6245.8 6249.1 6259.2 6278.7 6106.6 6161.8 6186.2 6196.3 6214.8 6123.9 6134.7 6163.5 6182.6 6209.3 4331.6 4824.3 4909.6 4961.2 4964.0 4337.8 4389.0 4423.6 4431.4 4456.8 5602.3 5643.6 5692.3 5702.7 5706.4 6138.9 6157.1 6178.4 6198.5 6233.3 5694.7 5700.3 5703.9 5708.1 5713.1 5370.7 5406.3 5416.0 5423.1 5432.3 6206.4 6221.7 6245.4 6254.4 6337.2 6247.5 6250.2 6273.6 6281.2 6293.5 6151.7 6260.1 6310.7 6332.4 6340.3 6103.7 6111.4 6138.1 6155.6 6205.2 6251.0 6261.3 6263.5 6266.9 6282.4 5868.9 5926.6 6081.9 6090.1 6107.7 4822.4 6138.0 6142.1 6147.9 6150.7 5990.6 6000.8 6005.7 6052.0 6068.1 5891.4 6047.3 6094.2 6147.8 6149.6 5998.0 6013.8 6019.2 6031.4 6062.5 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - PROCESS | 1967 | [#1] ext.html Cycles:180 Average:73518.67 Median:18679.48 stddev:162028.87 (867.4%) stddev-sans-first:162390.64 15:03:26 INFO - PROCESS | 1967 | Values: 496.3 523.3 526.8 530.2 536.1 31008.0 32648.2 32695.7 43198.9 44585.0 45396.5 45806.8 45831.3 45932.3 46003.0 9619.2 9797.5 9801.7 9803.9 9817.3 8745.4 8925.6 9552.9 11517.8 11737.2 11742.5 11812.0 11816.4 11826.6 11831.9 8738.3 10137.8 11780.0 11899.3 11919.5 11398.8 11695.2 11705.4 11850.5 11865.7 41736.8 42117.0 42119.4 42179.8 42193.4 3126.2 3293.5 3324.0 3594.8 4115.2 28576.7 29700.6 30195.4 30445.8 30747.4 2715.6 2717.2 2733.5 2762.8 2859.9 512490.0 520523.4 522892.8 523132.0 523688.0 67444.7 68006.0 68056.0 68092.7 68143.6 70617.0 70849.6 70923.6 70975.6 70987.3 16824.7 17273.8 17301.0 17335.2 17370.7 11888.6 12899.9 13064.3 13138.0 13140.5 19582.8 20146.6 20203.0 20211.4 20241.5 7018.0 8358.2 8437.5 8457.9 8518.8 1003.3 1016.0 1033.5 1107.3 1185.4 26945.6 29667.0 29805.0 30253.2 30481.2 1011.6 1076.0 1130.7 1200.6 1298.7 12762.0 13486.8 15495.2 15831.5 15927.4 695450.0 704620.0 705736.0 706070.0 707860.0 61789.0 63049.7 63240.7 63263.7 63351.0 1330.9 1364.6 1366.6 1492.2 1515.2 25691.7 31010.7 31026.8 31591.4 31644.2 53407.9 53960.5 54054.7 54079.5 54159.2 559747.0 580487.0 580505.0 582055.0 583638.0 22917.5 24091.4 24598.6 24612.6 24982.0 35164.7 39012.0 40517.6 45813.9 46805.8 30630.4 37943.8 38358.6 38980.4 40125.0 37387.8 37710.8 37958.8 38488.6 39086.8 32263.0 37817.4 38805.6 39114.4 39117.8 31934.7 33155.0 34035.0 34135.5 34166.5 24870.0 24985.1 25101.7 25731.9 26117.2 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - PROCESS | 1967 | [#2] jquery.html Cycles:180 Average:34432.25 Median:11937.03 stddev:101394.93 (849.4%) stddev-sans-first:101647.08 15:03:26 INFO - PROCESS | 1967 | Values: 353.5 380.5 394.7 396.9 424.1 18014.3 20803.2 21436.5 21440.9 21565.9 12102.8 12119.4 12292.1 12437.6 12449.3 5366.0 5525.1 5525.8 5580.2 5591.0 3928.1 4059.5 4192.2 4194.3 5870.8 6178.8 6232.0 6234.9 6242.9 6308.5 6488.7 6648.6 6658.2 6890.7 6898.3 5314.2 5671.2 5680.8 5898.7 5947.3 13391.9 13773.4 13834.7 14026.6 14051.5 1854.2 2080.8 2113.0 2141.3 2308.2 9798.9 9888.8 10235.2 10322.8 10371.7 1569.2 1680.7 1715.5 1723.7 1745.8 78279.0 112918.4 118257.0 118477.0 119356.0 11537.9 11601.1 11617.7 11862.6 11878.2 13436.0 15309.0 15359.1 15470.4 15503.5 8624.5 8893.5 8923.0 8938.4 9036.8 7348.5 8821.5 8847.6 8941.8 8964.7 4211.2 4597.3 4653.3 4760.9 4762.6 1611.6 1807.4 1848.2 1984.4 1990.9 914.7 967.3 993.5 1023.6 1042.6 15977.8 20751.8 21229.4 21243.4 21377.4 970.5 981.2 1021.0 1024.3 1036.6 10979.7 11313.9 11328.2 11379.0 11422.6 598655.0 599157.8 610350.0 610950.0 616055.0 79594.6 114385.0 142892.0 149140.0 153862.0 453.8 454.4 471.8 479.4 481.6 11149.4 11153.9 11383.9 11552.1 11849.6 51226.5 53163.0 54734.7 56653.0 57517.4 66631.0 82489.0 85481.6 86991.0 88599.1 7961.4 8033.8 8437.6 8714.3 8871.7 6572.4 11052.0 12363.6 12907.5 13014.9 10857.8 11390.3 11516.1 11571.1 11814.9 10686.0 11369.9 11506.5 11772.9 11776.9 9407.2 9513.6 11809.6 11857.2 11961.3 8362.0 8448.6 8488.7 8757.7 8759.6 8578.3 10917.3 11470.9 11644.9 11902.9 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - PROCESS | 1967 | [#3] mootools.html Cycles:180 Average:5887.67 Median:4963.00 stddev:3329.09 (67.1%) stddev-sans-first:3320.33 15:03:26 INFO - PROCESS | 1967 | Values: 1269.8 1485.2 1604.0 1840.2 2000.9 6850.5 8908.3 9149.0 9171.5 9275.5 8897.7 9129.3 9136.1 9150.0 9166.3 2134.9 2195.9 2198.6 2426.7 2458.8 2224.3 2251.8 2393.0 2457.7 2461.7 2419.9 2423.3 2426.6 2433.9 2434.9 2381.7 2430.4 2433.4 2433.5 2437.5 2164.8 2238.5 2240.9 2242.1 2396.7 8870.2 9104.8 9112.8 9120.0 9123.5 5426.9 5605.8 5607.7 5608.0 5611.2 3549.2 3627.3 3757.1 3763.0 3764.8 1012.3 1034.3 1061.7 1076.7 1080.9 12264.8 12291.5 12295.4 12300.9 12307.0 4902.7 4932.2 5053.4 5057.3 5073.1 10367.0 10402.4 10430.2 10433.9 10440.4 5686.0 5688.5 5695.1 5710.0 5712.4 4606.6 4955.8 5018.9 5170.0 5170.9 3776.8 3864.1 4096.0 4102.7 4209.0 1316.3 1343.2 1353.5 1355.5 1367.2 799.6 929.1 935.9 967.7 983.1 5808.7 5848.2 5951.3 6006.8 6073.7 914.8 927.0 970.7 979.2 1027.7 3471.4 4108.9 4122.4 4135.3 4185.2 10649.9 10990.6 11173.9 11181.8 11205.2 11078.5 11095.6 11108.5 11112.1 11117.4 7100.4 8224.6 8423.5 8431.6 8512.4 6084.3 6119.7 6186.7 6253.1 6343.2 9899.2 10057.3 10325.2 10332.0 10341.3 10305.3 10599.1 10644.7 10647.6 10767.5 7714.0 7718.6 7758.5 7829.6 7870.9 8399.7 8443.2 8472.1 8486.4 8486.7 7396.5 7581.8 7590.4 7720.7 7728.7 7385.1 7460.0 7461.7 7650.5 7663.8 7081.8 7139.3 7144.2 7144.9 7152.2 4801.5 4970.2 5077.7 5092.0 5099.8 4850.8 5028.3 5043.7 5113.2 5318.4 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - PROCESS | 1967 | [#4] prototype.html Cycles:180 Average:4204.70 Median:4580.35 stddev:1232.61 (26.9%) stddev-sans-first:1200.95 15:03:26 INFO - PROCESS | 1967 | Values: 312.5 315.4 325.0 325.2 328.4 5064.4 5364.3 5436.7 5479.9 5504.3 5126.5 5458.5 5474.3 5488.2 5496.8 3980.3 4063.9 4125.1 4148.1 4193.2 3716.2 4197.3 4217.7 4235.7 4241.6 3889.2 4079.6 4152.5 4186.0 4224.0 3715.7 3727.9 4166.9 4181.2 4187.8 3078.7 3347.6 3388.3 3412.6 3701.3 4764.6 4870.3 5177.2 5222.7 5225.7 4418.9 4529.3 4816.9 4828.3 4837.8 4810.8 4867.8 5730.3 5753.3 5764.2 3469.3 4012.8 4035.9 4071.3 4095.7 4552.6 5015.0 5319.9 5450.0 6100.9 2874.4 3931.7 4073.0 4091.4 4108.0 3650.6 3725.5 4163.9 4197.2 4206.1 4471.9 4630.3 4793.3 4801.2 4830.4 5360.9 5370.4 5645.1 5733.3 5809.7 1202.5 1352.5 1365.8 1386.6 1389.5 737.7 806.6 814.3 829.6 844.8 3954.0 4117.7 4307.5 4340.6 4481.4 4724.6 4741.9 4747.4 4907.5 5065.9 4042.5 4080.6 4518.9 4559.9 4566.7 2233.2 2240.8 2339.8 2507.8 2580.2 4732.9 4846.7 4969.7 5026.0 5181.3 4314.6 4460.0 4470.5 4738.0 4805.1 4517.4 4620.2 4778.6 5243.8 5270.2 4442.5 4459.3 4600.3 4973.8 5027.3 4807.2 4911.2 4926.1 4954.7 4978.3 4503.6 4832.8 5215.8 5231.4 5275.8 3147.6 3421.6 3450.2 3479.3 3499.7 4485.9 4858.5 4960.5 5020.9 5064.0 4258.9 4628.6 4841.5 4863.1 4874.6 4318.0 4339.8 4683.6 4781.7 4808.6 4198.5 4594.0 4691.5 4752.7 4758.0 3786.9 4362.5 4367.8 4620.0 4832.3 4304.6 4325.8 4744.7 4755.7 4791.7 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - PROCESS | 1967 | [#5] yui.html Cycles:180 Average:18756.52 Median:628.15 stddev:26914.08 (4284.7%) stddev-sans-first:26955.30 15:03:26 INFO - PROCESS | 1967 | Values: 665.8 744.2 749.6 752.2 770.5 14013.9 17166.9 17995.3 18037.4 18067.1 15478.4 16374.9 16462.7 16469.7 16530.9 929.1 947.2 957.6 957.7 958.6 932.3 944.2 944.6 946.6 947.2 991.4 991.9 993.0 995.4 997.0 985.2 985.5 986.5 986.6 988.9 735.0 735.4 735.8 738.3 738.4 14977.4 15309.2 15325.5 15340.4 15346.6 12416.4 12417.4 12552.5 12659.4 13044.5 9890.6 10244.1 10259.7 10307.6 10550.2 3377.6 3456.9 3482.4 3503.3 3547.6 63536.0 66245.7 66517.3 84464.0 85996.1 9175.9 9423.0 9440.2 9622.9 9778.0 20507.6 21144.5 21170.8 21171.8 21536.1 7713.0 7741.5 7776.1 8030.7 8106.9 3971.8 4031.6 4085.7 4134.1 4389.1 3628.7 3737.7 3738.1 3738.2 3739.3 661.6 679.7 711.8 714.4 735.2 565.2 586.3 594.2 606.1 613.7 8204.2 8535.3 8651.0 8657.8 8739.7 509.5 561.3 605.8 623.5 632.8 3939.3 3967.6 3993.9 3999.4 4065.2 96593.5 98356.0 98946.0 101835.0 101905.0 69185.0 86936.1 87482.0 87554.1 88537.0 79040.2 80694.6 81464.6 81862.0 82367.2 7764.5 7775.3 7879.0 7918.0 7997.8 49104.5 49414.5 49430.0 50719.5 51594.0 52748.0 65729.7 70147.0 71024.6 71092.0 10871.8 11095.8 11110.7 11177.4 11274.2 11157.8 11225.7 11279.8 11314.9 11330.7 10592.7 10665.3 10671.9 10940.1 11052.0 10553.6 10574.8 10670.6 10776.2 10883.2 10392.7 10397.8 10430.9 10447.3 10462.9 8941.7 8988.1 9030.7 9167.7 9365.4 8400.0 8850.6 8899.9 8997.0 9300.8 15:03:26 INFO - PROCESS | 1967 | -------- Summary: end -------- 15:03:26 INFO - PROCESS | 1967 | 15:03:26 INFO - TEST-INFO | 1967: exit 0 15:03:26 INFO - TEST-OK | dromaeo_css | took 1115532ms 15:03:26 INFO - TEST-START | kraken 15:03:26 INFO - operating with platform_type : mac_ 15:03:26 INFO - Initialising browser for kraken test... 15:03:26 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpWcyYyr/profile http://localhost:52195/getInfo.html) 15:03:28 INFO - PROCESS | 1971 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 15:03:29 INFO - PROCESS | 1971 | __metrics Screen width/height:1600/1200 15:03:29 INFO - PROCESS | 1971 | colorDepth:24 15:03:29 INFO - PROCESS | 1971 | Browser inner width/height: 1024/689 15:03:29 INFO - PROCESS | 1971 | __metrics 15:03:29 INFO - PROCESS | 1971 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:03:29 ERROR - PROCESS | 1971 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:03:29 INFO - TEST-INFO | 1971: exit 0 15:03:29 INFO - Browser initialized. 15:03:29 INFO - Running cycle 1/1 for kraken test... 15:03:29 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpWcyYyr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:03:41 INFO - PROCESS | 1973 | RSS: Main: 239878144 15:03:41 INFO - PROCESS | 1973 | 15:03:44 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52195/tests/kraken/driver.html?testName=audio-beat-detection) 15:03:45 INFO - PROCESS | 1973 | RSS: Main: 287137792 15:03:45 INFO - PROCESS | 1973 | 15:03:48 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52195/tests/kraken/driver.html?testName=audio-dft) 15:03:48 INFO - PROCESS | 1973 | RSS: Main: 297943040 15:03:48 INFO - PROCESS | 1973 | 15:03:52 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52195/tests/kraken/driver.html?testName=audio-fft) 15:03:53 INFO - PROCESS | 1973 | RSS: Main: 385609728 15:03:53 INFO - PROCESS | 1973 | 15:03:55 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52195/tests/kraken/driver.html?testName=audio-oscillator) 15:03:55 INFO - PROCESS | 1973 | RSS: Main: 287023104 15:03:55 INFO - PROCESS | 1973 | 15:03:57 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52195/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:03:58 INFO - PROCESS | 1973 | RSS: Main: 290922496 15:03:58 INFO - PROCESS | 1973 | 15:04:01 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52195/tests/kraken/driver.html?testName=imaging-darkroom) 15:04:02 INFO - PROCESS | 1973 | RSS: Main: 328085504 15:04:02 INFO - PROCESS | 1973 | 15:04:05 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52195/tests/kraken/driver.html?testName=imaging-desaturate) 15:04:06 INFO - PROCESS | 1973 | RSS: Main: 329404416 15:04:06 INFO - PROCESS | 1973 | 15:04:09 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52195/tests/kraken/driver.html?testName=json-parse-financial) 15:04:09 INFO - PROCESS | 1973 | RSS: Main: 327106560 15:04:09 INFO - PROCESS | 1973 | 15:04:11 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52195/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:04:12 INFO - PROCESS | 1973 | RSS: Main: 316649472 15:04:12 INFO - PROCESS | 1973 | 15:04:14 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52195/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:04:14 INFO - PROCESS | 1973 | RSS: Main: 323858432 15:04:14 INFO - PROCESS | 1973 | 15:04:17 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52195/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:04:17 INFO - PROCESS | 1973 | RSS: Main: 325681152 15:04:17 INFO - PROCESS | 1973 | 15:04:20 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52195/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:04:21 INFO - PROCESS | 1973 | RSS: Main: 329932800 15:04:21 INFO - PROCESS | 1973 | 15:04:24 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52195/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:04:24 INFO - PROCESS | 1973 | RSS: Main: 327692288 15:04:24 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:52195/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52195/tests/kraken/driver.html?testName=ai-astar) 15:04:26 INFO - PROCESS | 1973 | RSS: Main: 374759424 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | __start_tp_report 15:04:26 INFO - PROCESS | 1973 | _x_x_mozilla_page_load 15:04:26 INFO - PROCESS | 1973 | _x_x_mozilla_page_load_details 15:04:26 INFO - PROCESS | 1973 | |i|pagename|runs| 15:04:26 INFO - PROCESS | 1973 | |0;ai-astar;112;106;112;111;112;112;113;113;112;112 15:04:26 INFO - PROCESS | 1973 | |1;audio-beat-detection;159;118;143;199;120;140;140;100;184;138 15:04:26 INFO - PROCESS | 1973 | |2;audio-dft;184;185;186;184;183;187;181;183;181;184 15:04:26 INFO - PROCESS | 1973 | |3;audio-fft;80;76;48;127;76;81;77;80;117;55 15:04:26 INFO - PROCESS | 1973 | |4;audio-oscillator;93;98;132;79;79;77;102;79;80;100 15:04:26 INFO - PROCESS | 1973 | |5;imaging-gaussian-blur;116;122;88;84;88;84;89;84;87;71 15:04:26 INFO - PROCESS | 1973 | |6;imaging-darkroom;93;108;129;147;125;92;94;93;93;92 15:04:26 INFO - PROCESS | 1973 | |7;imaging-desaturate;83;84;84;83;86;83;84;84;84;95 15:04:26 INFO - PROCESS | 1973 | |8;json-parse-financial;58;56;57;57;57;56;58;120;59;55 15:04:26 INFO - PROCESS | 1973 | |9;json-stringify-tinderbox;64;67;80;73;70;66;66;68;67;67 15:04:26 INFO - PROCESS | 1973 | |10;stanford-crypto-aes;85;107;104;102;103;97;82;80;85;84 15:04:26 INFO - PROCESS | 1973 | |11;stanford-crypto-ccm;145;145;126;137;135;136;131;138;215;138 15:04:26 INFO - PROCESS | 1973 | |12;stanford-crypto-pbkdf2;163;164;162;165;164;165;162;166;163;161 15:04:26 INFO - PROCESS | 1973 | |13;stanford-crypto-sha256-iterative;67;64;66;64;66;64;65;62;64;64 15:04:26 INFO - PROCESS | 1973 | __end_tp_report 15:04:26 INFO - PROCESS | 1973 | __start_cc_report 15:04:26 INFO - PROCESS | 1973 | _x_x_mozilla_cycle_collect,2309 15:04:26 INFO - PROCESS | 1973 | __end_cc_report 15:04:26 INFO - PROCESS | 1973 | __startTimestamp1463263466784__endTimestamp 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | ------- Summary: start ------- 15:04:26 INFO - PROCESS | 1973 | Number of tests: 14 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:2.01 (1.8%) stddev-sans-first:2.13 15:04:26 INFO - PROCESS | 1973 | Values: 112.0 106.0 112.0 111.0 112.0 112.0 113.0 113.0 112.0 112.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#1] audio-beat-detection Cycles:10 Average:144.10 Median:141.50 stddev:30.01 (21.2%) stddev-sans-first:31.35 15:04:26 INFO - PROCESS | 1973 | Values: 159.0 118.0 143.0 199.0 120.0 140.0 140.0 100.0 184.0 138.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.93 (1.1%) stddev-sans-first:2.05 15:04:26 INFO - PROCESS | 1973 | Values: 184.0 185.0 186.0 184.0 183.0 187.0 181.0 183.0 181.0 184.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.50 stddev:24.13 (31.5%) stddev-sans-first:25.59 15:04:26 INFO - PROCESS | 1973 | Values: 80.0 76.0 48.0 127.0 76.0 81.0 77.0 80.0 117.0 55.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:79.00 stddev:17.25 (21.8%) stddev-sans-first:18.29 15:04:26 INFO - PROCESS | 1973 | Values: 93.0 98.0 132.0 79.0 79.0 77.0 102.0 79.0 80.0 100.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:85.50 stddev:15.53 (18.2%) stddev-sans-first:13.66 15:04:26 INFO - PROCESS | 1973 | Values: 116.0 122.0 88.0 84.0 88.0 84.0 89.0 84.0 87.0 71.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#6] imaging-darkroom Cycles:10 Average:106.60 Median:92.50 stddev:20.04 (21.7%) stddev-sans-first:20.64 15:04:26 INFO - PROCESS | 1973 | Values: 93.0 108.0 129.0 147.0 125.0 92.0 94.0 93.0 93.0 92.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:84.00 stddev:3.62 (4.3%) stddev-sans-first:3.77 15:04:26 INFO - PROCESS | 1973 | Values: 83.0 84.0 84.0 83.0 86.0 83.0 84.0 84.0 84.0 95.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:57.00 stddev:19.96 (35.0%) stddev-sans-first:21.07 15:04:26 INFO - PROCESS | 1973 | Values: 58.0 56.0 57.0 57.0 57.0 56.0 58.0 120.0 59.0 55.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:67.50 stddev:4.64 (6.9%) stddev-sans-first:4.58 15:04:26 INFO - PROCESS | 1973 | Values: 64.0 67.0 80.0 73.0 70.0 66.0 66.0 68.0 67.0 67.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:83.00 stddev:10.61 (12.8%) stddev-sans-first:10.86 15:04:26 INFO - PROCESS | 1973 | Values: 85.0 107.0 104.0 102.0 103.0 97.0 82.0 80.0 85.0 84.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:138.00 stddev:25.38 (18.4%) stddev-sans-first:26.92 15:04:26 INFO - PROCESS | 1973 | Values: 145.0 145.0 126.0 137.0 135.0 136.0 131.0 138.0 215.0 138.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:164.00 stddev:1.58 (1.0%) stddev-sans-first:1.67 15:04:26 INFO - PROCESS | 1973 | Values: 163.0 164.0 162.0 165.0 164.0 165.0 162.0 166.0 163.0 161.0 15:04:26 INFO - PROCESS | 1973 | 15:04:26 INFO - PROCESS | 1973 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.50 stddev:1.43 (2.2%) stddev-sans-first:1.22 15:04:26 INFO - PROCESS | 1973 | Values: 67.0 64.0 66.0 64.0 66.0 64.0 65.0 62.0 64.0 64.0 15:04:26 INFO - PROCESS | 1973 | -------- Summary: end -------- 15:04:26 INFO - PROCESS | 1973 | 15:04:27 INFO - TEST-INFO | 1973: exit 0 15:04:27 INFO - TEST-OK | kraken | took 60887ms 15:04:27 INFO - SUITE-END | took 1176s 15:04:27 INFO - Completed test suite (00:19:36) 15:04:27 INFO - javascript benchmark 15:04:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3242.5784647995756, 3450.2581944881863, 3462.7576069675133, 3484.1638006878356, 3509.8069606171666, 5613.887722245553, 5635.126555383922, 5649.6327738696955, 5712.828615141547, 5782.53739700824, 5666.065099258628, 5718.6568805871675, 5723.685197314146, 5728.112142617891, 5757.539396848252, 5176.456472070426, 5197.870053248661, 5222.060029194743, 5239.869003274911, 5331.2269720890445, 5222.294990176316, 5245.580353571724, 5283.996040752264, 5315.468453154692, 5324.8019195681045, 4526.3210518422175, 5185.351831021122, 5206.167013277876, 5238.0, 5269.341332333458, 4636.188666983281, 4793.664443488957, 5151.6136289778415, 5301.655392399482, 5312.5218730314455, 5092.0, 5253.86865328366, 5265.447128051538, 5273.31446911899, 5295.364556253236, 5586.497215250647, 5615.7472913718575, 5735.885282294373, 5744.626599271034, 5751.424857514257, 6059.518647548154, 6210.410011048988, 6222.688865556749, 6224.782132625336, 6262.405071518198, 6094.756209751605, 6103.1881284819, 6155.44600985909, 6180.907286390708, 6208.1308616793685, 5728.341240757366, 5733.627314224562, 5784.595078344518, 5797.681127537984, 5810.767569297265, 6245.718942647592, 6252.718627661766, 6265.718042687999, 6266.404691554295, 6273.749050037947, 6026.186464827279, 6133.110698948627, 6150.692465376714, 6168.722407491674, 6178.073289006595, 6231.813045608639, 6245.843853903689, 6249.062640603855, 6259.2488901332135, 6278.748850046039, 6106.603070800384, 6161.784337548164, 6186.226721659793, 6196.349383314777, 6214.751409943642, 6123.892747182667, 6134.662593557308, 6163.506919446433, 6182.598131121463, 6209.316975132731, 4331.566843315643, 4824.300476430898, 4909.582685471775, 4961.181405068123, 4964.0, 4337.82648694055, 4388.990532177617, 4423.60187583119, 4431.423914891108, 4456.7548784817145, 5602.299712535933, 5643.604947653707, 5692.345380281238, 5702.714864256771, 5706.3722990471015, 6138.878900475516, 6157.1072194532435, 6178.382161783786, 6198.504119670415, 6233.345498722569, 5694.715264236855, 5700.3444603871085, 5703.8859222816145, 5708.086706127, 5713.114467257498, 5370.731463426813, 5406.297181366476, 5415.972920135374, 5423.078076726966, 5432.266644003009, 6206.441420272107, 6221.688915554295, 6245.437910588069, 6254.437100660963, 6337.176167098432, 6247.5001999840815, 6250.249970003629, 6273.62358258506, 6281.152044473937, 6293.465055470337, 6151.661658608819, 6260.123582698335, 6310.7475700971445, 6332.366763323631, 6340.334264902303, 6103.694815259308, 6111.449969502766, 6138.081874381997, 6155.599886007395, 6205.193324867739, 6250.968745156245, 6261.279952805486, 6263.467605253604, 6266.905996409966, 6282.434580887742, 5868.911966320594, 5926.644401335933, 6081.878362432815, 6090.116933044772, 6107.69461526913, 4822.373091498082, 6137.974468425319, 6142.1132067928, 6147.907781383372, 6150.661713605616, 5990.550708696778, 6000.849978750566, 6005.699715014319, 6051.969740151271, 6068.059450785136, 5891.351951285441, 6047.304559975659, 6094.177286066324, 6147.815565533041, 6149.631022138686, 5998.040313549811, 6013.819585412444, 6019.157317975574, 6031.4270144336215, 6062.545309101747], "value": 5682.7575638671215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.30517275813855, 523.2909407752493, 526.7629566694986, 530.2073400266602, 536.0940011380766, 31008.0, 32648.183795405126, 32695.67304326956, 43198.92002699934, 44585.0, 45396.54603453964, 45806.7709661452, 45831.31253031199, 45932.311015334795, 46003.0, 9619.230461563058, 9797.510124493783, 9801.656942007032, 9803.901960980405, 9817.263705222136, 8745.431546949429, 8925.55372231389, 9552.904470955305, 11517.827232591519, 11737.178397512178, 11742.471588778526, 11812.0, 11816.409179540988, 11826.645200643994, 11831.940840295787, 8738.344624153215, 10137.847932281022, 11780.0, 11899.34553599556, 11919.463624136848, 11398.772024559463, 11695.239809412446, 11705.414729263588, 11850.525978960832, 11865.703357416047, 41736.79131604323, 42117.0, 42119.36820947688, 42179.789101054295, 42193.36709949353, 3126.1559378967777, 3293.5389045533643, 3323.9501407478906, 3594.784312941206, 4115.176964607091, 28576.714232857612, 29700.554491682644, 30195.396092078034, 30445.847770761004, 30747.385052299054, 2715.592661100831, 2717.2255907066587, 2733.5489644208683, 2762.8342299461897, 2859.8713057912446, 512490.0, 520523.39738301444, 522892.771072292, 523132.0, 523688.0, 67444.6627766858, 68006.0, 68056.0, 68092.659536702, 68143.63712725717, 70617.0, 70849.64575177194, 70923.64538177276, 70975.64512177405, 70987.2901270991, 16824.663506729798, 17273.827261727474, 17301.0, 17335.21991510385, 17370.739438908426, 11888.583899563473, 12899.871521198897, 13064.346782660828, 13138.0, 13140.474381024653, 19582.754380879167, 20146.597068058556, 20202.989850507416, 20211.393658190278, 20241.493962651053, 7018.017342629362, 8358.247757701836, 8437.451565648213, 8457.915420845713, 8518.78703032429, 1003.2525768302661, 1015.9586869924465, 1033.5452400943561, 1107.307932542161, 1185.4191446191305, 26945.595816062774, 29666.961656341926, 29805.0, 30253.243668908453, 30481.237969050508, 1011.5751384418562, 1076.0333948256778, 1130.745582244005, 1200.6458094862037, 1298.727267273864, 12761.971199362237, 13486.797698034537, 15495.225238738018, 15831.52505424816, 15927.442539511061, 695450.0, 704620.0, 705736.0, 706070.0, 707860.0, 61789.0, 63049.68475157595, 63240.683796581645, 63263.68368158222, 63351.0, 1330.9001824863174, 1364.6179069860257, 1366.583192126411, 1492.1793013842343, 1515.2348064227494, 25691.743082569683, 31010.68989310078, 31026.844865775525, 31591.366992795203, 31644.208894777814, 53407.93184136373, 53960.460395395545, 54054.729726351114, 54079.45920540902, 54159.18761218506, 559747.0, 580487.0, 580505.0, 582055.0, 583638.0, 22917.54164916659, 24091.397715056562, 24598.631020535064, 24612.6308105375, 24982.0, 35164.71500427509, 39012.0, 40517.59482405256, 45813.854653632596, 46805.76597117129, 30630.387392252476, 37943.81028094842, 38358.61641383662, 38980.41529377005, 40125.0, 37387.813060934524, 37710.81144594259, 37958.810205948794, 38488.6151138485, 39086.804565976985, 32263.032109036765, 37817.432738509495, 38805.6119438802, 39114.41328380133, 39117.82646520609, 31934.68065319317, 33155.0, 34035.0, 34135.487967681, 34166.487502686985, 24870.00519979253, 24985.125520606693, 25101.74898251067, 25731.871340643178, 26117.216483505526], "value": 20613.51805877736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.52274429520133, 380.4939430557359, 394.68819632490323, 396.8749843799204, 424.1157187264555, 18014.279428822847, 20803.16787328509, 21436.464088397777, 21440.892795536038, 21565.892170539144, 12102.81845772314, 12119.394030298494, 12292.078094142953, 12437.564685235995, 12449.253044817317, 5365.953639040389, 5525.143602741561, 5525.806596769114, 5580.218769372291, 5591.02157122504, 3928.057266256092, 4059.4722686050773, 4192.24539582875, 4194.328907374821, 5870.794522191724, 6178.814635560941, 6231.968840155793, 6234.875302493947, 6242.875142497147, 6308.495320374359, 6488.740450381979, 6648.634325112117, 6658.201015878126, 6890.689918953634, 6898.275681053492, 5314.176302673091, 5671.234383358235, 5680.772769089232, 5898.675572843492, 5947.316058653267, 13391.92864570832, 13773.449062037607, 13834.723305533833, 14026.649333766636, 14051.508197213048, 1854.1656254685445, 2080.78151794061, 2112.9894350528148, 2141.3468891987936, 2308.1690591387087, 9798.902010979942, 9888.802223955481, 10235.181185505216, 10322.845157322647, 10371.688849334532, 1569.2467615544488, 1680.7142785726455, 1715.5368050626262, 1723.7069698151345, 1745.8341457561512, 78279.0, 112918.43540782326, 118257.0, 118477.0, 119356.0, 11537.88462115385, 11601.12991525639, 11617.6514704559, 11862.584809531625, 11878.227915185658, 13436.0, 15309.00491468051, 15359.078455292718, 15470.381184752709, 15503.457378991681, 8624.482531048157, 8893.4663920165, 8922.955385223162, 8938.374313798102, 9036.819263614823, 7348.52234604749, 8821.470711757314, 8847.557622118868, 8941.821163576693, 8964.731058068268, 4211.1998720243055, 4597.264437689953, 4653.302004699322, 4760.857174284777, 4762.64280178988, 1611.6454380036373, 1807.394522834865, 1848.1775609853755, 1984.3947595983363, 1990.8905010224287, 914.6570036236411, 967.274544091931, 993.5181437003048, 1023.6007956896816, 1042.635077722791, 15977.840221597635, 20751.792482074987, 21229.363119106143, 21243.36269911905, 21377.35867923965, 970.4807927758616, 981.249344251648, 1020.9693709188737, 1024.3393011507535, 1036.6216331039116, 10979.670609881716, 11313.886861131283, 11328.150388720715, 11379.0, 11422.600208992812, 598655.0, 599157.8337445897, 610350.0, 610950.0, 616055.0, 79594.6020269895, 114385.0, 142892.0, 149140.0, 153862.0, 453.82754553269547, 454.422882938666, 471.8419329524579, 479.36244794423334, 481.5714014527074, 11149.386783726974, 11153.88846111561, 11383.943080284545, 11552.075833933433, 11849.585264516045, 51226.48773512217, 53163.0, 54734.72632636971, 56653.0, 57517.42482575288, 66631.0, 82489.0, 85481.57259213664, 86991.0, 88599.11400885909, 7961.402894782798, 8033.799155021171, 8437.620307086074, 8714.346424018097, 8871.689490867788, 6572.401912393723, 11052.0, 12363.62909112728, 12907.483700651868, 13014.934925325313, 10857.782844342912, 11390.25963312382, 11516.136289778133, 11571.132165087483, 11814.936655701029, 10685.96966576348, 11369.943150284196, 11506.482208300815, 11772.941135294268, 11776.882231177578, 9407.247420206504, 9513.571889264862, 11809.645710628694, 11857.16999810005, 11961.282323060645, 8361.95819020901, 8448.619812108593, 8488.74533763988, 8757.737267881974, 8759.605817738344, 8578.270846978077, 10917.253239481963, 11470.885291146982, 11644.941775291069, 11902.940485297519], "value": 8956.960001119542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1269.8031805070218, 1485.227681605565, 1603.9524902684893, 1840.2087102545909, 2000.9499762505927, 6850.451963842893, 8908.331875109368, 9149.039350868146, 9171.54142292886, 9275.536223188847, 8897.733068007954, 9129.269658427309, 9136.086391360861, 9150.0, 9166.266698664107, 2134.9146034158616, 2195.934121976335, 2198.626233540294, 2426.672399226104, 2458.8032957363366, 2224.3438185735167, 2251.8423710340285, 2392.9521409571885, 2457.741937096606, 2461.6676748638884, 2419.891104900275, 2423.260905423845, 2426.5874801283826, 2433.866137362453, 2434.8782560871887, 2381.7022872140983, 2430.3924018995253, 2433.3551608823677, 2433.537627850703, 2437.4515733959893, 2164.7510536288214, 2238.507528343762, 2240.8879556022134, 2242.1479837661586, 2396.724376696685, 8870.246029087535, 9104.772380690469, 9112.772180695469, 9120.042395548504, 9123.543822808899, 5426.945730542723, 5605.831825045255, 5607.663540187602, 5607.971960140214, 5611.158326251054, 3549.201429678302, 3627.2564124354444, 3757.102663155089, 3762.9623703762886, 3764.7552909061096, 1012.266107072374, 1034.3431920730316, 1061.665575343772, 1076.6500887211687, 1080.8865069167703, 12264.81602775959, 12291.508339666403, 12295.385230738517, 12300.876991230152, 12307.0, 4902.681325713817, 4932.1861892788, 5053.393592768818, 5057.266696329012, 5073.11220536412, 10367.0, 10402.375857448576, 10430.217733670004, 10433.895661043443, 10440.42577658221, 5685.971570142123, 5688.544916406678, 5695.088785794336, 5710.0, 5712.428757124254, 4606.56237657422, 4955.826546070943, 5018.89962200754, 5169.974150129226, 5170.922436163461, 3776.848061341317, 3864.053306939818, 4096.0, 4102.671786257092, 4208.978955105266, 1316.3089378076395, 1343.2209318595192, 1353.472145863114, 1355.5323413422354, 1367.2138520350838, 799.552250739576, 929.149827907457, 935.9344845860725, 967.7145242153582, 983.1053741095711, 5808.651480911159, 5848.181254624356, 5951.285845698544, 6006.789762358297, 6073.6659483727935, 914.7804526913493, 926.9814603707888, 970.6845275285419, 979.1725991537139, 1027.660871912267, 3471.3751524725285, 4108.856190033334, 4122.381642753551, 4135.32519329672, 4185.162967406531, 10649.893501065046, 10990.560377584807, 11173.944130279458, 11181.83227251592, 11205.159613029055, 11078.501467433985, 11095.55617775296, 11108.500117494732, 11112.11103111749, 11117.388543630179, 7100.360967512948, 8224.62989165489, 8423.494590324477, 8431.578421079044, 8512.361572882059, 6084.300305464752, 6119.724612392364, 6186.721597528212, 6253.062040693932, 6343.175387199634, 9899.208063355058, 10057.29598928068, 10325.225608079274, 10332.018458246455, 10341.327813692085, 10305.278630495794, 10599.099076578268, 10644.733881653021, 10647.627333043307, 10767.515461804394, 7714.035745531809, 7718.575478348745, 7758.534487930742, 7829.6085195741125, 7870.921290787019, 8399.748007559783, 8443.240108390275, 8472.110428405174, 8486.363522735695, 8486.660533578588, 7396.482246242711, 7581.848363032819, 7590.392768578611, 7720.691172353268, 7728.690852365842, 7385.076865391826, 7459.962700186465, 7461.738839140604, 7650.464467487445, 7663.846723065396, 7081.82295442618, 7139.286071392819, 7144.178419481618, 7144.857102858018, 7152.249013853679, 4801.4718380977365, 4970.179920313108, 5077.695338279715, 5092.0, 5099.847004589868, 4850.811551169986, 5028.321176641107, 5043.672161309503, 5113.15632920564, 5318.441563635917], "value": 4712.233562907655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.5124805303726, 315.35195173917606, 325.0443695535128, 325.2162288883791, 328.3646144709988, 5064.366954130734, 5364.275822763926, 5436.700981446019, 5479.917801232979, 5504.284443022407, 5126.48768613259, 5458.4814442627885, 5474.315710536183, 5488.231647569343, 5496.835094947148, 3980.263651224518, 4063.923060388999, 4125.051238215212, 4148.149629325996, 4193.203291374629, 3716.2381711749026, 4197.307444271692, 4217.725847819897, 4235.68232382571, 4241.554636763141, 3889.2221555688834, 4079.551249362568, 4152.522459917103, 4185.958140418603, 4224.007358270804, 3715.665590096877, 3727.9375378017407, 4166.91666166678, 4181.205570941512, 4187.790610469479, 3078.676169247245, 3347.6484969078383, 3388.339273841602, 3412.6178897546533, 3701.2782507411066, 4764.571188593009, 4870.318155458239, 5177.249298851645, 5222.73886305687, 5225.660332078441, 4418.911621767546, 4529.343245229456, 4816.927746083811, 4828.251620998751, 4837.806487740486, 4810.759462026885, 4867.829625963074, 5730.312362516524, 5753.338366087912, 5764.221830052973, 3469.300042978922, 4012.839486420569, 4035.9192816143805, 4071.3282308419075, 4095.672346212296, 4552.632631031878, 5014.974925125388, 5319.941033905481, 5449.972750136264, 6100.877982440371, 2874.439484300583, 3931.68546516278, 4073.0, 4091.447654566655, 4108.0, 3650.5984341722387, 3725.4598083277774, 4163.937540936889, 4197.181549597799, 4206.095689426788, 4471.932921006188, 4630.328602352707, 4793.257045158006, 4801.18379875422, 4830.396200474941, 5360.8659783504945, 5370.355557333067, 5645.068563687028, 5733.254676891977, 5809.7095145242565, 1202.5370232460566, 1352.48605529898, 1365.8497565267812, 1386.646405166676, 1389.4650559534653, 737.668508342447, 806.6289506826926, 814.267159556404, 829.6225217526011, 844.8425656850031, 3954.0114971257185, 4117.676439738323, 4307.480124127421, 4340.63270069927, 4481.3726078349055, 4724.622030237572, 4741.928871066937, 4747.382840230817, 4907.509249075135, 5065.898682026339, 4042.4542686736913, 4080.5511393746665, 4518.876128219731, 4559.931601025921, 4566.725996440225, 2233.218373569238, 2240.80117137334, 2339.82451316152, 2507.796257796268, 2580.20709556951, 4732.905341893143, 4846.655807492819, 4969.726665033386, 5026.045051440215, 5181.274621552986, 4314.633256173261, 4459.996500787297, 4470.530594287618, 4737.957649317144, 4805.087033463631, 4517.435320584927, 4620.168369693421, 4778.593819525379, 5243.8274843719, 5270.183121616155, 4442.533533979015, 4459.2865141577195, 4600.3329517219145, 4973.776180071978, 5027.271045698426, 4807.245355445168, 4911.189653707242, 4926.137925862888, 4954.662241194929, 4978.30303757467, 4503.572160644733, 4832.815960089801, 5215.791368345223, 5231.398389185292, 5275.788968441373, 3147.6222853257755, 3421.5894092709036, 3450.1892055366466, 3479.3041391722263, 3499.720022398253, 4485.865424037284, 4858.5384388483035, 4960.503949605155, 5020.924686129784, 5064.0, 4258.850940217201, 4628.560286772751, 4841.491643377535, 4863.060920654281, 4874.610031197567, 4317.956820431881, 4339.7613131278085, 4683.601893838925, 4781.6891902026255, 4808.639352048541, 4198.517170525307, 4593.966357569654, 4691.519598783222, 4752.714837109785, 4757.976210118927, 3786.8674596388996, 4362.454693163355, 4367.847125350598, 4620.0, 4832.323474713613, 4304.579488768762, 4325.805338759827, 4744.7390393528685, 4755.667103302736, 4791.688540244873], "value": 3820.586979773367, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.8202285382947, 744.226004954847, 749.5577609210565, 752.1951511882285, 770.5299767142039, 14013.859861401383, 17166.939967203936, 17995.28018879245, 18037.36869209578, 18067.096645167756, 15478.380864765395, 16374.918125409356, 16462.67074658506, 16469.67060658789, 16530.917345413258, 929.0709290709308, 947.2327414794039, 957.5547370472729, 957.6504575829807, 958.611762236292, 932.3333816321328, 944.1596978688972, 944.593824655398, 946.5693109635134, 947.1854205383365, 991.3803872579638, 991.8760154980628, 992.9602815887356, 995.437577768562, 996.9601215951353, 985.2265971212599, 985.5170966226569, 986.5264672956949, 986.5708416838662, 988.8763904511936, 734.9816254593678, 735.3933005270619, 735.8417940142841, 738.3133685672323, 738.4129616954534, 14977.400903963828, 15309.157996310198, 15325.46360877364, 15340.386384544605, 15346.616334591612, 12416.379181041002, 12417.379131043412, 12552.497900083985, 12659.367031648473, 13044.543440979614, 9890.554925028391, 10244.129249013768, 10259.743506412326, 10307.587696492132, 10550.208734344878, 3377.611574668895, 3456.948145777815, 3482.4428091505492, 3503.2643144939593, 3547.592026916912, 63536.0, 66245.66877165632, 66517.33482665208, 84464.0, 85996.14003859943, 9175.908240917639, 9423.0, 9440.197583205367, 9622.9037709622, 9778.0, 20507.58984820295, 21144.471388215417, 21170.788292116882, 21171.78828211729, 21536.13855445796, 7713.0, 7741.535507869546, 7776.144624091346, 8030.71892483772, 8106.878396824052, 3971.781552014609, 4031.5766844481495, 4085.6935729820384, 4134.111166099303, 4389.122175564899, 3628.6734193922675, 3737.663610275088, 3738.102855314705, 3738.2149748552565, 3739.3082279778328, 661.6063442251852, 679.6678510120121, 711.8469529051279, 714.3677845107118, 735.1729304532402, 565.2341077839468, 586.302300262686, 594.1622312539299, 606.139282219241, 613.6747523812387, 8204.179582041868, 8535.274501667303, 8651.0, 8657.783555411039, 8739.737807865775, 509.53377659441935, 561.3236050559067, 605.8061420345447, 623.5354660777749, 632.7638789020889, 3939.2515422069714, 3967.563568007517, 3993.880183594497, 3999.420084087791, 4065.207284579538, 96593.51703241438, 98356.0, 98946.0, 101835.0, 101905.0, 69185.0, 86936.13063869407, 87482.0, 87554.12445875588, 88537.0, 79040.20959790329, 80694.59652701698, 81464.59267703624, 81862.0, 82367.17632823595, 7764.526769855465, 7775.261350171724, 7878.960605196938, 7918.0, 7997.8400431992195, 49104.50895491142, 49414.50585494243, 49430.0, 50719.49280507147, 51593.96812063813, 52748.0, 65729.67135164485, 70147.0, 71024.64487677529, 71092.0, 10871.782564348827, 11095.833562496731, 11110.722231944266, 11177.441127943734, 11274.154438416988, 11157.832632510357, 11225.66323010311, 11279.774404512027, 11314.886851131383, 11330.660080197607, 10592.682219533426, 10665.253432259979, 10671.893281067401, 10940.106254263606, 11052.0, 10553.577856885639, 10574.84137737919, 10670.62652807148, 10776.191785616267, 10883.183761217782, 10392.688219353431, 10397.792044159225, 10430.895691042992, 10447.268691191848, 10462.895371046496, 8941.687040953535, 8988.056568887218, 9030.729078127666, 9167.724968250963, 9365.363573062876, 8399.95800020996, 8850.557472126497, 8899.911000889908, 8997.01032886369, 9300.813983720423], "value": 7017.13232486058, "unit": "score"}], "name": "dromaeo_css", "value": 7140.679431664967, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 111.0, 112.0, 112.0, 113.0, 113.0, 112.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 118.0, 143.0, 199.0, 120.0, 140.0, 140.0, 100.0, 184.0, 138.0], "value": 144.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 186.0, 184.0, 183.0, 187.0, 181.0, 183.0, 181.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 48.0, 127.0, 76.0, 81.0, 77.0, 80.0, 117.0, 55.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 98.0, 132.0, 79.0, 79.0, 77.0, 102.0, 79.0, 80.0, 100.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 122.0, 88.0, 84.0, 88.0, 84.0, 89.0, 84.0, 87.0, 71.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 108.0, 129.0, 147.0, 125.0, 92.0, 94.0, 93.0, 93.0, 92.0], "value": 106.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 83.0, 86.0, 83.0, 84.0, 84.0, 84.0, 95.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 57.0, 57.0, 57.0, 56.0, 58.0, 120.0, 59.0, 55.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 80.0, 73.0, 70.0, 66.0, 66.0, 68.0, 67.0, 67.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 107.0, 104.0, 102.0, 103.0, 97.0, 82.0, 80.0, 85.0, 84.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 126.0, 137.0, 135.0, 136.0, 131.0, 138.0, 215.0, 138.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 162.0, 165.0, 164.0, 165.0, 162.0, 166.0, 163.0, 161.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 66.0, 64.0, 66.0, 64.0, 65.0, 62.0, 64.0, 64.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1493.6, "alertThreshold": 2.0}]} 15:04:28 INFO - Return code: 0 15:04:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:04:28 ERROR - # TBPL SUCCESS # 15:04:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:04:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:04:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:04:28 INFO - 2016-05-14 15:04:28.094 screenresolution[1977:16665] starting screenresolution argv=screenresolution get 15:04:28 INFO - 2016-05-14 15:04:28.097 screenresolution[1977:16665] Display 0: 1600x1200x32@60 15:04:28 INFO - 2016-05-14 15:04:28.103 screenresolution[1978:16668] starting screenresolution argv=screenresolution list 15:04:28 INFO - Available Modes on Display 0 15:04:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:04:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:04:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:04:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:04:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:04:28 INFO - Intel Iris: 15:04:28 INFO - Chipset Model: Intel Iris 15:04:28 INFO - Type: GPU 15:04:28 INFO - Bus: Built-In 15:04:28 INFO - VRAM (Dynamic, Max): 1536 MB 15:04:28 INFO - Vendor: Intel (0x8086) 15:04:28 INFO - Device ID: 0x0a2e 15:04:28 INFO - Revision ID: 0x0009 15:04:28 INFO - Displays: 15:04:28 INFO - Display: 15:04:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:04:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:04:28 INFO - Display Serial Number: 1600x1200 60 15:04:28 INFO - Main Display: Yes 15:04:28 INFO - Mirror: Off 15:04:28 INFO - Online: Yes 15:04:28 INFO - Rotation: Supported 15:04:28 INFO - Return code: 0 15:04:28 INFO - Running post-action listener: _resource_record_post_action 15:04:28 INFO - ##### 15:04:28 INFO - ##### Finished run-tests step (success) 15:04:28 INFO - ##### 15:04:28 INFO - Running post-run listener: _resource_record_post_run 15:04:28 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 446464; Write bytes: 696593408; Read time: 6; Write time: 2216 15:04:28 INFO - TinderboxPrint: CPU usage
54.0% 15:04:28 INFO - TinderboxPrint: I/O read bytes / time
446,464 / 6 15:04:28 INFO - TinderboxPrint: I/O write bytes / time
696,593,408 / 2,216 15:04:28 INFO - TinderboxPrint: CPU idle
2,231.6 (46.0%) 15:04:28 INFO - TinderboxPrint: CPU system
967.9 (20.0%) 15:04:28 INFO - TinderboxPrint: CPU user
1,650.1 (34.0%) 15:04:28 INFO - TinderboxPrint: Swap in / out
394,399,744 / 0 15:04:28 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375960064; Write bytes: 370124800; Read time: 28151; Write time: 725 15:04:28 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 438272; Write bytes: 299459584; Read time: 6; Write time: 1437 15:04:28 INFO - Running post-run listener: _upload_blobber_files 15:04:28 INFO - Blob upload gear active. 15:04:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:04:28 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 15:04:28 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'] 15:04:28 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 15:04:29 INFO - (blobuploader) - INFO - Open directory for files ... 15:04:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:04:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:04:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:04:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:04:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:04:29 INFO - (blobuploader) - INFO - Done attempting. 15:04:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:04:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:04:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:04:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:04:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:04:31 INFO - (blobuploader) - INFO - Done attempting. 15:04:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:04:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:04:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:04:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:04:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:04:33 INFO - (blobuploader) - INFO - Done attempting. 15:04:33 INFO - (blobuploader) - INFO - Iteration through files over. 15:04:33 INFO - Return code: 0 15:04:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:04:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:04:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51e71d57b61653939966d800881c52467994d0ac881c3402ce6636e6bac08941544a3d08453f44105849149396e34f686814b5db6ace8cb4ac19af9698b671c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ffe09b27a124ba9af19cf3b3d6aaddeaf629795a59291b25054bca51a598c2a467df74e77e26d037fa3f291a7e3cbac8aee52f07e6c3bde3175934ffc15d10d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/254e564988b3b7c37827628663e4898979e9aa4fb3963c6d2c57d611d76b6a533ae2e8579c4a04ca8207289314df45b2817ac78beea060a509ef8dc1c2fe83d9"} 15:04:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:04:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:04:33 INFO - Contents: 15:04:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51e71d57b61653939966d800881c52467994d0ac881c3402ce6636e6bac08941544a3d08453f44105849149396e34f686814b5db6ace8cb4ac19af9698b671c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ffe09b27a124ba9af19cf3b3d6aaddeaf629795a59291b25054bca51a598c2a467df74e77e26d037fa3f291a7e3cbac8aee52f07e6c3bde3175934ffc15d10d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/254e564988b3b7c37827628663e4898979e9aa4fb3963c6d2c57d611d76b6a533ae2e8579c4a04ca8207289314df45b2817ac78beea060a509ef8dc1c2fe83d9"} 15:04:33 INFO - Running post-run listener: copy_logs_to_upload_dir 15:04:33 INFO - Copying logs to upload dir... 15:04:33 INFO - mkdir: /builds/slave/test/build/upload/logs 15:04:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1310.718245 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 50 secs) (at 2016-05-14 15:04:33.919692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 15:04:33.923561) ========= 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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51e71d57b61653939966d800881c52467994d0ac881c3402ce6636e6bac08941544a3d08453f44105849149396e34f686814b5db6ace8cb4ac19af9698b671c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ffe09b27a124ba9af19cf3b3d6aaddeaf629795a59291b25054bca51a598c2a467df74e77e26d037fa3f291a7e3cbac8aee52f07e6c3bde3175934ffc15d10d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/254e564988b3b7c37827628663e4898979e9aa4fb3963c6d2c57d611d76b6a533ae2e8579c4a04ca8207289314df45b2817ac78beea060a509ef8dc1c2fe83d9"} build_url:https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/CTj8EiRNTQudEJ5oo0NLVw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51e71d57b61653939966d800881c52467994d0ac881c3402ce6636e6bac08941544a3d08453f44105849149396e34f686814b5db6ace8cb4ac19af9698b671c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ffe09b27a124ba9af19cf3b3d6aaddeaf629795a59291b25054bca51a598c2a467df74e77e26d037fa3f291a7e3cbac8aee52f07e6c3bde3175934ffc15d10d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/254e564988b3b7c37827628663e4898979e9aa4fb3963c6d2c57d611d76b6a533ae2e8579c4a04ca8207289314df45b2817ac78beea060a509ef8dc1c2fe83d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 15:04:33.951651) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:04:33.951962) ========= 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.rUpyyyAK1j/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.vy0Lu4ASN4/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.005295 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:04:34.001159) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 15:04:34.001491) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 15:04:34.001860) ========= ========= Total master_lag: 0.16 =========