builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0313 starttime: 1468593959.93 results: success (0) buildid: 20160714100743 builduid: c872b8ab7bb44532a943333c2596d4c5 revision: 36faf180f49d6e6af84f55231052b1bac85b3bcd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 07:45:59.930514) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 07:45:59.931059) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 07:45:59.931376) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 07:45:59.956818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:45:59.957145) ========= 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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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-07-15 07:45:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 200M=0s 2016-07-15 07:46:00 (200 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.598731 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:00.579047) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:00.579422) ========= 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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.034256 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:00.656625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:00.657049) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 36faf180f49d6e6af84f55231052b1bac85b3bcd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 36faf180f49d6e6af84f55231052b1bac85b3bcd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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-07-15 07:46:00,739 truncating revision to first 12 chars 2016-07-15 07:46:00,739 Setting DEBUG logging. 2016-07-15 07:46:00,739 attempt 1/10 2016-07-15 07:46:00,740 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/36faf180f49d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 07:46:01,029 unpacking tar archive at: mozilla-beta-36faf180f49d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.553814 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:01.264855) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:01.265224) ========= script_repo_revision: 36faf180f49d6e6af84f55231052b1bac85b3bcd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:01.265666) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:01.265965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 07:46:01.284427) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-15 07:46:01.285011) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:01 INFO - MultiFileLogger online at 20160715 07:46:01 in /builds/slave/test 07:46:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:46:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:46:01 INFO - {'append_to_log': False, 07:46:01 INFO - 'base_work_dir': '/builds/slave/test', 07:46:01 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:46:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:46:01 INFO - 'branch': 'Mozilla-Beta', 07:46:01 INFO - 'buildbot_json_path': 'buildprops.json', 07:46:01 INFO - 'config_files': ('talos/mac_config.py',), 07:46:01 INFO - 'default_actions': ('clobber', 07:46:01 INFO - 'read-buildbot-config', 07:46:01 INFO - 'download-and-extract', 07:46:01 INFO - 'populate-webroot', 07:46:01 INFO - 'create-virtualenv', 07:46:01 INFO - 'install', 07:46:01 INFO - 'run-tests'), 07:46:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:46:01 INFO - 'download_minidump_stackwalk': True, 07:46:01 INFO - 'download_symbols': 'ondemand', 07:46:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:46:01 INFO - 'tooltool.py': '/tools/tooltool.py', 07:46:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:46:01 INFO - '/tools/misc-python/virtualenv.py')}, 07:46:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:46:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:46:01 INFO - 'installer_path': 'installer.exe', 07:46:01 INFO - 'log_level': 'info', 07:46:01 INFO - 'log_name': 'talos', 07:46:01 INFO - 'log_to_console': True, 07:46:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:46:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:46:01 INFO - 'opt_config_files': (), 07:46:01 INFO - 'pip_index': False, 07:46:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:46:01 INFO - 'cmd': ('bash', 07:46:01 INFO - '-c', 07:46:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:46:01 INFO - 'enabled': True, 07:46:01 INFO - 'halt_on_failure': False, 07:46:01 INFO - 'name': 'check_screen_resolution'},), 07:46:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:46:01 INFO - 'cmd': ('bash', 07:46:01 INFO - '-c', 07:46:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:46:01 INFO - 'enabled': True, 07:46:01 INFO - 'halt_on_failure': False, 07:46:01 INFO - 'name': 'check_screen_resolution'},), 07:46:01 INFO - 'run_cmd_checks_enabled': True, 07:46:01 INFO - 'sps_profile': False, 07:46:01 INFO - 'sps_profile_interval': 0, 07:46:01 INFO - 'suite': 'dromaeojs', 07:46:01 INFO - 'system_bits': '32', 07:46:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:46:01 INFO - 'title': 't-yosemite-r7-0313', 07:46:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:46:01 INFO - 'use_talos_json': True, 07:46:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:46:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:46:01 INFO - 'work_dir': 'build'} 07:46:01 INFO - ##### 07:46:01 INFO - ##### Running clobber step. 07:46:01 INFO - ##### 07:46:01 INFO - Running pre-action listener: _resource_record_pre_action 07:46:01 INFO - Running main action method: clobber 07:46:01 INFO - rmtree: /builds/slave/test/build 07:46:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:46:03 INFO - Running post-action listener: _resource_record_post_action 07:46:03 INFO - ##### 07:46:03 INFO - ##### Running read-buildbot-config step. 07:46:03 INFO - ##### 07:46:03 INFO - Running pre-action listener: _resource_record_pre_action 07:46:03 INFO - Running main action method: read_buildbot_config 07:46:03 INFO - Using buildbot properties: 07:46:03 INFO - { 07:46:03 INFO - "project": "", 07:46:03 INFO - "product": "firefox", 07:46:03 INFO - "script_repo_revision": "production", 07:46:03 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 07:46:03 INFO - "repository": "", 07:46:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 07:46:03 INFO - "buildid": "20160714100743", 07:46:03 INFO - "pgo_build": "False", 07:46:03 INFO - "basedir": "/builds/slave/test", 07:46:03 INFO - "buildnumber": 36, 07:46:03 INFO - "slavename": "t-yosemite-r7-0313", 07:46:03 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:46:03 INFO - "platform": "yosemite_r7", 07:46:03 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 07:46:03 INFO - "branch": "mozilla-beta", 07:46:03 INFO - "repo_path": "releases/mozilla-beta", 07:46:03 INFO - "revision": "36faf180f49d6e6af84f55231052b1bac85b3bcd", 07:46:03 INFO - "stage_platform": "macosx64", 07:46:03 INFO - "builduid": "c872b8ab7bb44532a943333c2596d4c5", 07:46:03 INFO - "slavebuilddir": "test" 07:46:03 INFO - } 07:46:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 07:46:03 INFO - Running post-action listener: _resource_record_post_action 07:46:03 INFO - ##### 07:46:03 INFO - ##### Running download-and-extract step. 07:46:03 INFO - ##### 07:46:03 INFO - Running pre-action listener: _resource_record_pre_action 07:46:03 INFO - Running main action method: download_and_extract 07:46:03 INFO - mkdir: /builds/slave/test/build/tests 07:46:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:03 INFO - https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:46:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 07:46:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 07:46:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 07:46:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 07:46:04 INFO - Downloaded 1165 bytes. 07:46:04 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 07:46:04 INFO - Using the following test package requirements: 07:46:04 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 07:46:04 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 07:46:04 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 07:46:04 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 07:46:04 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 07:46:04 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:46:04 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 07:46:04 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 07:46:04 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 07:46:04 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 07:46:04 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 07:46:04 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 07:46:04 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 07:46:04 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 07:46:04 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 07:46:04 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 07:46:04 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 07:46:04 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 07:46:04 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')]} 07:46:04 INFO - https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:46:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 07:46:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 07:46:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 07:46:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 07:46:05 INFO - Downloaded 18314840 bytes. 07:46:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:46:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:46:08 INFO - Return code: 0 07:46:08 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 07:46: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')]} 07:46:08 INFO - https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:46:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 07:46:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 07:46:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 07:46:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 07:46:10 INFO - Downloaded 18314840 bytes. 07:46:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:46:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:46:13 INFO - Return code: 0 07:46: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')]} 07:46:13 INFO - https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:46:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 07:46:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 07:46:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 07:46:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 07:46:14 INFO - Downloaded 10840145 bytes. 07:46:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:46:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:46:14 INFO - Return code: 0 07:46:14 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')]} 07:46:14 INFO - https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 07:46:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 07:46:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 07:46:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 07:46:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 07:46:17 INFO - Downloaded 86190619 bytes. 07:46:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 07:46:17 INFO - mkdir: /builds/slave/test/properties 07:46:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:46:17 INFO - Writing to file /builds/slave/test/properties/build_url 07:46:17 INFO - Contents: 07:46:17 INFO - build_url:https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 07:46:18 INFO - Running post-action listener: _resource_record_post_action 07:46:18 INFO - Running post-action listener: set_extra_try_arguments 07:46:18 INFO - ##### 07:46:18 INFO - ##### Running populate-webroot step. 07:46:18 INFO - ##### 07:46:18 INFO - Running pre-action listener: _resource_record_pre_action 07:46:18 INFO - Running main action method: populate_webroot 07:46:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:46:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:46:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:46:18 INFO - '--tppagecycles', 07:46:18 INFO - '7'], 07:46:18 INFO - 'tests': ['tsvgm']}}, 07:46:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:46:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:46:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:46:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:46:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:18 INFO - 'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:46:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:46:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:18 INFO - 'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['damp', 'tps']}, 07:46:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:18 INFO - 'tests': ['damp', 'tps']}, 07:46:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['dromaeo_dom']}, 07:46:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:46:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['a11yr', 07:46:18 INFO - 'ts_paint', 07:46:18 INFO - 'tpaint', 07:46:18 INFO - 'sessionrestore', 07:46:18 INFO - 'sessionrestore_no_auto_restore', 07:46:18 INFO - 'tabpaint']}, 07:46:18 INFO - 'other-e10s': {'tests': ['a11yr', 07:46:18 INFO - 'ts_paint', 07:46:18 INFO - 'tpaint', 07:46:18 INFO - 'sessionrestore', 07:46:18 INFO - 'sessionrestore_no_auto_restore', 07:46:18 INFO - 'tabpaint']}, 07:46:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:46:18 INFO - 'ts_paint', 07:46:18 INFO - 'tpaint', 07:46:18 INFO - 'sessionrestore', 07:46:18 INFO - 'sessionrestore_no_auto_restore', 07:46:18 INFO - 'tabpaint']}, 07:46:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:46:18 INFO - 'ts_paint', 07:46:18 INFO - 'tpaint', 07:46:18 INFO - 'sessionrestore', 07:46:18 INFO - 'sessionrestore_no_auto_restore', 07:46:18 INFO - 'tabpaint']}, 07:46:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['a11yr', 07:46:18 INFO - 'ts_paint', 07:46:18 INFO - 'tpaint', 07:46:18 INFO - 'sessionrestore', 07:46:18 INFO - 'sessionrestore_no_auto_restore', 07:46:18 INFO - 'tabpaint']}, 07:46:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['a11yr', 07:46:18 INFO - 'ts_paint', 07:46:18 INFO - 'tpaint', 07:46:18 INFO - 'sessionrestore', 07:46:18 INFO - 'sessionrestore_no_auto_restore', 07:46:18 INFO - 'tabpaint']}, 07:46:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['tsvgx', 07:46:18 INFO - 'tsvgr_opacity', 07:46:18 INFO - 'tart', 07:46:18 INFO - 'tscrollx', 07:46:18 INFO - 'cart']}, 07:46:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:46:18 INFO - 'tsvgr_opacity', 07:46:18 INFO - 'tart', 07:46:18 INFO - 'tscrollx', 07:46:18 INFO - 'cart']}, 07:46:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:18 INFO - 'talos_options': ['--disable-e10s'], 07:46:18 INFO - 'tests': ['tp5o']}, 07:46:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:18 INFO - 'tests': ['tp5o']}, 07:46:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:18 INFO - 'talos_options': ['--disable-e10s', 07:46:18 INFO - '--xperf_path', 07:46:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:46:18 INFO - 'tests': ['tp5n']}, 07:46:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:46:18 INFO - 'talos_options': ['--xperf_path', 07:46:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:46:18 INFO - 'tests': ['tp5n']}}, 07:46:18 INFO - 'talos.zip': {'path': '', 07:46:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:46:18 INFO - Running post-action listener: _resource_record_post_action 07:46:18 INFO - ##### 07:46:18 INFO - ##### Running create-virtualenv step. 07:46:18 INFO - ##### 07:46:18 INFO - Running pre-action listener: _resource_record_pre_action 07:46:18 INFO - Running main action method: create_virtualenv 07:46:18 INFO - Creating virtualenv /builds/slave/test/build/venv 07:46:18 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 07:46:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:46:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:46:18 INFO - Using real prefix '/tools/python27' 07:46:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:46:19 INFO - Installing distribute.............................................................................................................................................................................................done. 07:46:21 INFO - Installing pip.................done. 07:46:21 INFO - Return code: 0 07:46:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:46:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:46:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:21 INFO - 'HOME': '/Users/cltbld', 07:46:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:21 INFO - 'LOGNAME': 'cltbld', 07:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:21 INFO - 'MOZ_NO_REMOTE': '1', 07:46:21 INFO - 'NO_EM_RESTART': '1', 07:46:21 INFO - 'PAGER': '/bin/cat', 07:46:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:21 INFO - 'PWD': '/builds/slave/test', 07:46:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:21 INFO - 'SHELL': '/bin/bash', 07:46:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:21 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:21 INFO - 'USER': 'cltbld', 07:46:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:21 INFO - 'XPC_FLAGS': '0x0', 07:46:21 INFO - 'XPC_SERVICE_NAME': '0', 07:46:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:22 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 07:46:22 INFO - Cleaning up... 07:46:22 INFO - Return code: 0 07:46:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:22 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:22 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>=0.7.1'] in /builds/slave/test/build 07:46:22 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>=0.7.1 07:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:22 INFO - 'HOME': '/Users/cltbld', 07:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:22 INFO - 'LOGNAME': 'cltbld', 07:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:22 INFO - 'MOZ_NO_REMOTE': '1', 07:46:22 INFO - 'NO_EM_RESTART': '1', 07:46:22 INFO - 'PAGER': '/bin/cat', 07:46:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:22 INFO - 'PWD': '/builds/slave/test', 07:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:22 INFO - 'SHELL': '/bin/bash', 07:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:22 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:22 INFO - 'USER': 'cltbld', 07:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:22 INFO - 'XPC_FLAGS': '0x0', 07:46:22 INFO - 'XPC_SERVICE_NAME': '0', 07:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:22 INFO - Downloading/unpacking psutil>=0.7.1 07:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:46:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:46:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:46:25 INFO - Installing collected packages: psutil 07:46:25 INFO - Running setup.py install for psutil 07:46:25 INFO - building 'psutil._psutil_osx' extension 07:46:25 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 07:46:26 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 07:46:26 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 07:46:26 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 07:46:26 INFO - building 'psutil._psutil_posix' extension 07:46:26 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 07:46:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:46:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:46:26 INFO - ^ 07:46:26 INFO - 1 warning generated. 07:46:26 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 07:46:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:46:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:46:26 INFO - Successfully installed psutil 07:46:26 INFO - Cleaning up... 07:46:27 INFO - Return code: 0 07:46:27 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:46:27 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')]} 07:46:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:27 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')]} 07:46:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:27 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:27 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.0.0'] in /builds/slave/test/build 07:46:27 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.0.0 07:46:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:27 INFO - 'HOME': '/Users/cltbld', 07:46:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:27 INFO - 'LOGNAME': 'cltbld', 07:46:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:27 INFO - 'MOZ_NO_REMOTE': '1', 07:46:27 INFO - 'NO_EM_RESTART': '1', 07:46:27 INFO - 'PAGER': '/bin/cat', 07:46:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:27 INFO - 'PWD': '/builds/slave/test', 07:46:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:27 INFO - 'SHELL': '/bin/bash', 07:46:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:27 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:27 INFO - 'USER': 'cltbld', 07:46:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:27 INFO - 'XPC_FLAGS': '0x0', 07:46:27 INFO - 'XPC_SERVICE_NAME': '0', 07:46:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:46:27 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 07:46:27 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 07:46:27 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 07:46:27 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 07:46:27 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 07:46:27 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 07:46:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:46:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:46:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:46:29 INFO - Installing collected packages: mozsystemmonitor 07:46:29 INFO - Running setup.py install for mozsystemmonitor 07:46:29 INFO - Successfully installed mozsystemmonitor 07:46:29 INFO - Cleaning up... 07:46:30 INFO - Return code: 0 07:46:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:30 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 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:30 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 07:46:30 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 07:46:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:30 INFO - 'HOME': '/Users/cltbld', 07:46:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:30 INFO - 'LOGNAME': 'cltbld', 07:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:30 INFO - 'MOZ_NO_REMOTE': '1', 07:46:30 INFO - 'NO_EM_RESTART': '1', 07:46:30 INFO - 'PAGER': '/bin/cat', 07:46:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:30 INFO - 'PWD': '/builds/slave/test', 07:46:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:30 INFO - 'SHELL': '/bin/bash', 07:46:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:30 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:30 INFO - 'USER': 'cltbld', 07:46:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:30 INFO - 'XPC_FLAGS': '0x0', 07:46:30 INFO - 'XPC_SERVICE_NAME': '0', 07:46:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:30 INFO - Downloading/unpacking blobuploader==1.2.4 07:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:32 INFO - Downloading blobuploader-1.2.4.tar.gz 07:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:46:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:46:32 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 07:46:32 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 07:46:32 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 07:46:32 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 07:46:32 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 07:46:32 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 07:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:46:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:46:33 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 07:46:33 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 07:46:33 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 07:46:33 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 07:46:33 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 07:46:33 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 07:46:33 INFO - Downloading docopt-0.6.1.tar.gz 07:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:46:34 INFO - Installing collected packages: blobuploader, requests, docopt 07:46:34 INFO - Running setup.py install for blobuploader 07:46:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:46:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:46:34 INFO - Running setup.py install for requests 07:46:34 INFO - Running setup.py install for docopt 07:46:34 INFO - Successfully installed blobuploader requests docopt 07:46:34 INFO - Cleaning up... 07:46:34 INFO - Return code: 0 07:46:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:34 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 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:34 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 07:46:34 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 07:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:34 INFO - 'HOME': '/Users/cltbld', 07:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:34 INFO - 'LOGNAME': 'cltbld', 07:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:34 INFO - 'MOZ_NO_REMOTE': '1', 07:46:34 INFO - 'NO_EM_RESTART': '1', 07:46:34 INFO - 'PAGER': '/bin/cat', 07:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:34 INFO - 'PWD': '/builds/slave/test', 07:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:34 INFO - 'SHELL': '/bin/bash', 07:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:34 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:34 INFO - 'USER': 'cltbld', 07:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:34 INFO - 'XPC_FLAGS': '0x0', 07:46:34 INFO - 'XPC_SERVICE_NAME': '0', 07:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:46:35 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-jxx5L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:46:35 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-BN7NYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:46:35 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-xRuEPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:46:35 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-kHL159-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:46:35 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-e3UjnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:46:35 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-PGRExy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:46:35 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-VsDgaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:46:35 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Mbe0RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-W05CsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-TuSKiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-8nbEDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-nLYljY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-tZ5EWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-XFfL_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-cLuFlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-zEMJEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:46:36 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-MgNPLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:46:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:46:37 INFO - Running setup.py install for manifestparser 07:46:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:46:37 INFO - Running setup.py install for mozcrash 07:46:37 INFO - Running setup.py install for mozdebug 07:46:37 INFO - Running setup.py install for mozdevice 07:46:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:46:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:46:37 INFO - Running setup.py install for mozfile 07:46:37 INFO - Running setup.py install for mozhttpd 07:46:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:46:37 INFO - Running setup.py install for mozinfo 07:46:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Running setup.py install for mozInstall 07:46:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Running setup.py install for mozleak 07:46:38 INFO - Running setup.py install for mozlog 07:46:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Running setup.py install for moznetwork 07:46:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Running setup.py install for mozprocess 07:46:38 INFO - Running setup.py install for mozprofile 07:46:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:46:38 INFO - Running setup.py install for mozrunner 07:46:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:46:39 INFO - Running setup.py install for mozscreenshot 07:46:39 INFO - Running setup.py install for moztest 07:46:39 INFO - Running setup.py install for mozversion 07:46:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:46:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:46:39 INFO - Cleaning up... 07:46:39 INFO - Return code: 0 07:46:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:46:39 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')]} 07:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:39 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')]} 07:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:39 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 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:39 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 07:46:39 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 07:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:39 INFO - 'HOME': '/Users/cltbld', 07:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:39 INFO - 'LOGNAME': 'cltbld', 07:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:39 INFO - 'MOZ_NO_REMOTE': '1', 07:46:39 INFO - 'NO_EM_RESTART': '1', 07:46:39 INFO - 'PAGER': '/bin/cat', 07:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:39 INFO - 'PWD': '/builds/slave/test', 07:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:39 INFO - 'SHELL': '/bin/bash', 07:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:39 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:39 INFO - 'USER': 'cltbld', 07:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:39 INFO - 'XPC_FLAGS': '0x0', 07:46:39 INFO - 'XPC_SERVICE_NAME': '0', 07:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:46:39 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-5_0Zrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:46:39 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)) 07:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:46:39 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-axMbFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:46:40 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-OTsEAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:46:40 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Oyf23X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:46:40 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-I6nCeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:46:40 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-CZr1jH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:46:40 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-lx2siU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:46:40 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Ci7veo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:46:40 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-SEUm6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:46:40 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-ehzwnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 07:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:46:40 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-1XNCSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:46:41 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)) 07:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:46:41 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-vFJoiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:46:41 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)) 07:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:46:41 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-X6jWoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:46:41 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)) 07:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:46:41 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-UbaGnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:46:41 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)) 07:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:46:41 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-rgnb5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:46:41 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)) 07:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:46:41 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-wBc00w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:46:41 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)) 07:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:46:41 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-iczAvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:46:41 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)) 07:46:41 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)) 07:46:41 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)) 07:46:41 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)) 07:46:41 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)) 07:46:41 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)) 07:46:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:44 INFO - Downloading blessings-1.6.tar.gz 07:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:46:44 INFO - Installing collected packages: blessings 07:46:44 INFO - Running setup.py install for blessings 07:46:44 INFO - Successfully installed blessings 07:46:44 INFO - Cleaning up... 07:46:44 INFO - Return code: 0 07:46:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:46:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:46:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:46:44 INFO - Reading from file tmpfile_stdout 07:46:44 INFO - Current package versions: 07:46:44 INFO - blessings == 1.6 07:46:44 INFO - blobuploader == 1.2.4 07:46:44 INFO - docopt == 0.6.1 07:46:44 INFO - manifestparser == 1.1 07:46:44 INFO - mozInstall == 1.12 07:46:44 INFO - mozcrash == 0.17 07:46:44 INFO - mozdebug == 0.1 07:46:44 INFO - mozdevice == 0.48 07:46:44 INFO - mozfile == 1.2 07:46:44 INFO - mozhttpd == 0.7 07:46:44 INFO - mozinfo == 0.9 07:46:44 INFO - mozleak == 0.1 07:46:44 INFO - mozlog == 3.1 07:46:44 INFO - moznetwork == 0.27 07:46:44 INFO - mozprocess == 0.22 07:46:44 INFO - mozprofile == 0.28 07:46:44 INFO - mozrunner == 6.11 07:46:44 INFO - mozscreenshot == 0.1 07:46:44 INFO - mozsystemmonitor == 0.0 07:46:44 INFO - moztest == 0.7 07:46:44 INFO - mozversion == 1.4 07:46:44 INFO - psutil == 3.1.1 07:46:44 INFO - requests == 1.2.3 07:46:44 INFO - wsgiref == 0.1.2 07:46:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:46: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')]} 07:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46: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')]} 07:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:44 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 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:44 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 07:46:44 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 07:46:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:44 INFO - 'HOME': '/Users/cltbld', 07:46:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:44 INFO - 'LOGNAME': 'cltbld', 07:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:44 INFO - 'MOZ_NO_REMOTE': '1', 07:46:44 INFO - 'NO_EM_RESTART': '1', 07:46:44 INFO - 'PAGER': '/bin/cat', 07:46:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:44 INFO - 'PWD': '/builds/slave/test', 07:46:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:44 INFO - 'SHELL': '/bin/bash', 07:46:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:44 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:44 INFO - 'USER': 'cltbld', 07:46:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:44 INFO - 'XPC_FLAGS': '0x0', 07:46:44 INFO - 'XPC_SERVICE_NAME': '0', 07:46:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 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)) 07:46:44 INFO - Cleaning up... 07:46:44 INFO - Return code: 0 07:46:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:46: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')]} 07:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46: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')]} 07:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:46:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:46:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:44 INFO - 'HOME': '/Users/cltbld', 07:46:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:44 INFO - 'LOGNAME': 'cltbld', 07:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:44 INFO - 'MOZ_NO_REMOTE': '1', 07:46:44 INFO - 'NO_EM_RESTART': '1', 07:46:44 INFO - 'PAGER': '/bin/cat', 07:46:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:44 INFO - 'PWD': '/builds/slave/test', 07:46:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:44 INFO - 'SHELL': '/bin/bash', 07:46:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:44 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:44 INFO - 'USER': 'cltbld', 07:46:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:44 INFO - 'XPC_FLAGS': '0x0', 07:46:44 INFO - 'XPC_SERVICE_NAME': '0', 07:46:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:45 INFO - Downloading/unpacking jsonschema 07:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:46:47 INFO - Downloading/unpacking functools32 (from jsonschema) 07:46:47 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 07:46:47 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 07:46:47 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 07:46:47 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 07:46:47 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 07:46:47 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 07:46:48 INFO - Downloading functools32-3.2.3-2.tar.gz 07:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:46:48 INFO - warning: no files found matching '*.txt' 07:46:48 INFO - no previously-included directories found matching 'build' 07:46:48 INFO - no previously-included directories found matching 'dist' 07:46:48 INFO - no previously-included directories found matching '.git*' 07:46:48 INFO - Installing collected packages: jsonschema, functools32 07:46:48 INFO - Running setup.py install for functools32 07:46:48 INFO - warning: no files found matching '*.txt' 07:46:48 INFO - no previously-included directories found matching 'build' 07:46:48 INFO - no previously-included directories found matching 'dist' 07:46:48 INFO - no previously-included directories found matching '.git*' 07:46:48 INFO - Successfully installed jsonschema functools32 07:46:48 INFO - Cleaning up... 07:46:48 INFO - Return code: 0 07:46:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:46:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:48 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 0x10d11da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d12d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5bbc52710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, '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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 07:46:48 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 07:46:48 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 07:46:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:46:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:48 INFO - 'HOME': '/Users/cltbld', 07:46:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:48 INFO - 'LOGNAME': 'cltbld', 07:46:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:48 INFO - 'MOZ_NO_REMOTE': '1', 07:46:48 INFO - 'NO_EM_RESTART': '1', 07:46:48 INFO - 'PAGER': '/bin/cat', 07:46:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:48 INFO - 'PWD': '/builds/slave/test', 07:46:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:48 INFO - 'SHELL': '/bin/bash', 07:46:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:46:48 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:46:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:48 INFO - 'USER': 'cltbld', 07:46:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:48 INFO - 'XPC_FLAGS': '0x0', 07:46:48 INFO - 'XPC_SERVICE_NAME': '0', 07:46:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:48 INFO - Downloading/unpacking flake8 07:46:48 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 07:46:48 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 07:46:48 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 07:46:48 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 07:46:48 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 07:46:48 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 07:46:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:46:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:46:51 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 07:46:51 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 07:46:51 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 07:46:51 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 07:46:51 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 07:46:51 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 07:46:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:46:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:46:51 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 07:46:51 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 07:46:51 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 07:46:51 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 07:46:51 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 07:46:51 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 07:46:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:46:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:46:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:46:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:46:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:46:52 INFO - no previously-included directories found matching 'docs/_build' 07:46:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:46:52 INFO - Running setup.py install for pep8 07:46:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:46:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:46:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:46:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:46:52 INFO - no previously-included directories found matching 'docs/_build' 07:46:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:46:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:46:52 INFO - Cleaning up... 07:46:52 INFO - Return code: 0 07:46:52 INFO - Running post-action listener: _resource_record_post_action 07:46:52 INFO - Running post-action listener: _start_resource_monitoring 07:46:52 INFO - Starting resource monitoring. 07:46:52 INFO - ##### 07:46:52 INFO - ##### Running install step. 07:46:52 INFO - ##### 07:46:52 INFO - Running pre-action listener: _resource_record_pre_action 07:46:52 INFO - Running main action method: install 07:46:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:46:53 INFO - Reading from file tmpfile_stdout 07:46:53 INFO - Detecting whether we're running mozinstall >=1.0... 07:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:46:53 INFO - Reading from file tmpfile_stdout 07:46:53 INFO - Output received: 07:46:53 INFO - Usage: mozinstall [options] installer 07:46:53 INFO - Options: 07:46:53 INFO - -h, --help show this help message and exit 07:46:53 INFO - -d DEST, --destination=DEST 07:46:53 INFO - Directory to install application into. [default: 07:46:53 INFO - "/builds/slave/test"] 07:46:53 INFO - --app=APP Application being installed. [default: firefox] 07:46:53 INFO - mkdir: /builds/slave/test/build/application 07:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 07:47:15 INFO - Reading from file tmpfile_stdout 07:47:15 INFO - Output received: 07:47:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 07:47:15 INFO - Running post-action listener: _resource_record_post_action 07:47:15 INFO - ##### 07:47:15 INFO - ##### Running run-tests step. 07:47:15 INFO - ##### 07:47:15 INFO - Running pre-action listener: _resource_record_pre_action 07:47:15 INFO - Running main action method: run_tests 07:47:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:47:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:47:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:47:15 INFO - 2016-07-15 07:47:15.319 screenresolution[1651:8900] starting screenresolution argv=screenresolution get 07:47:15 INFO - 2016-07-15 07:47:15.322 screenresolution[1651:8900] Display 0: 1600x1200x32@60 07:47:15 INFO - 2016-07-15 07:47:15.328 screenresolution[1652:8902] starting screenresolution argv=screenresolution list 07:47:15 INFO - Available Modes on Display 0 07:47:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:47:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:47:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:47:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:47:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:47:15 INFO - Intel Iris: 07:47:15 INFO - Chipset Model: Intel Iris 07:47:15 INFO - Type: GPU 07:47:15 INFO - Bus: Built-In 07:47:15 INFO - VRAM (Dynamic, Max): 1536 MB 07:47:15 INFO - Vendor: Intel (0x8086) 07:47:15 INFO - Device ID: 0x0a2e 07:47:15 INFO - Revision ID: 0x0009 07:47:15 INFO - Displays: 07:47:15 INFO - Display: 07:47:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:47:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:47:15 INFO - Display Serial Number: 1600x1200 60 07:47:15 INFO - Main Display: Yes 07:47:15 INFO - Mirror: Off 07:47:15 INFO - Online: Yes 07:47:15 INFO - Rotation: Supported 07:47:15 INFO - Return code: 0 07:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:47:15 INFO - Python 2.7.3 07:47:15 INFO - Return code: 0 07:47:15 INFO - grabbing minidump binary from tooltool 07:47:15 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')]} 07:47:15 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbce00>, '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 0x10ce25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:47:15 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 07:47:15 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 07:47:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:47:15 INFO - Return code: 0 07:47:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:47:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:47:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:47:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:47:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:47:16 INFO - Return code: 0 07:47:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 07:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0313 --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 07:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZAOuh4hmxk/Render', 07:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:47:16 INFO - 'HOME': '/Users/cltbld', 07:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:47:16 INFO - 'LOGNAME': 'cltbld', 07:47:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:47:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:47:16 INFO - 'MOZ_NO_REMOTE': '1', 07:47:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:47:16 INFO - 'NO_EM_RESTART': '1', 07:47:16 INFO - 'PAGER': '/bin/cat', 07:47:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:47:16 INFO - 'PWD': '/builds/slave/test', 07:47:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:47:16 INFO - 'SHELL': '/bin/bash', 07:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iOBq1pw3WG/Listeners', 07:47:16 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 07:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:47:16 INFO - 'USER': 'cltbld', 07:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:47:16 INFO - 'XPC_FLAGS': '0x0', 07:47:16 INFO - 'XPC_SERVICE_NAME': '0', 07:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:47:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 07:47:16 INFO - mozversion application_buildid: 20160714100743 07:47:16 INFO - mozversion application_changeset: 36faf180f49d6e6af84f55231052b1bac85b3bcd 07:47:16 INFO - mozversion application_display_name: Firefox 07:47:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:47:16 INFO - mozversion application_name: Firefox 07:47:16 INFO - mozversion application_remotingname: firefox 07:47:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:47:16 INFO - mozversion application_vendor: Mozilla 07:47:16 INFO - mozversion application_version: 48.0 07:47:16 INFO - mozversion platform_buildid: 20160714100743 07:47:16 INFO - mozversion platform_changeset: 36faf180f49d6e6af84f55231052b1bac85b3bcd 07:47:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:47:16 INFO - mozversion platform_version: 48.0 07:47:16 INFO - using testdate: 1468594036 07:47:16 INFO - actual date: 1468594036 07:47:16 INFO - starting webserver on 'localhost:49283' 07:47:16 INFO - SUITE-START | Running 2 tests 07:47:16 INFO - TEST-START | dromaeo_css 07:47:16 INFO - operating with platform_type : mac_ 07:47:16 INFO - Initialising browser for dromaeo_css test... 07:47:17 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpl_PGie/profile http://localhost:49283/getInfo.html) 07:47:19 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 07:47:19 INFO - PROCESS | 1670 | colorDepth:24 07:47:19 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 07:47:19 INFO - PROCESS | 1670 | __metrics 07:47:19 INFO - PROCESS | 1670 | 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" 07:47:19 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:47:19 INFO - TEST-INFO | 1670: exit 0 07:47:19 INFO - Browser initialized. 07:47:19 INFO - Running cycle 1/1 for dromaeo_css test... 07:47:19 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpl_PGie/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:47:31 INFO - PROCESS | 1674 | RSS: Main: 160141312 07:47:31 INFO - PROCESS | 1674 | 07:48:23 INFO - PROCESS | 1674 | 1468594103240 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] 07:50:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 07:50:34 INFO - PROCESS | 1674 | RSS: Main: 292560896 07:50:34 INFO - PROCESS | 1674 | 07:53:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 07:53:38 INFO - PROCESS | 1674 | RSS: Main: 298459136 07:53:38 INFO - PROCESS | 1674 | 07:56:40 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 07:56:40 INFO - PROCESS | 1674 | RSS: Main: 306221056 07:56:40 INFO - PROCESS | 1674 | 07:59:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 07:59:43 INFO - PROCESS | 1674 | RSS: Main: 307007488 07:59:43 INFO - PROCESS | 1674 | 08:02:46 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 08:02:46 INFO - PROCESS | 1674 | RSS: Main: 270905344 08:02:46 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 08:05:49 INFO - PROCESS | 1674 | RSS: Main: 315760640 08:05:49 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | __start_tp_report 08:05:49 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 08:05:49 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 08:05:49 INFO - PROCESS | 1674 | |i|pagename|runs| 08:05:49 INFO - PROCESS | 1674 | |0;dojo.html;3466.878659246926;3585.6952159066477;3598.60415354311;3602.081469225347;3682.4660424238486;5966.940330596693;5990.1913241712355;6007.5494337924665;6016.51867850572;6071.878562428754;5990.730417131229;6037.516998640109;6049.092636104587;6054.0010898201745;6064.878702425949;5316.574674026088;5362.115250983593;5437.918431223535;5441.5102640762325;5502.367227768797;5383.623146379755;5463.31708536433;5463.972680136594;5475.616706830523;5478.233047373371;5372.838814835562;5436.592255580827;5437.293151890249;5446.891062178754;5455.890882182354;5458.099413596771;5472.2338872557475;5498.202760599731;5508.862278443031;5511.834644960658;4419.779011049434;5356.1430171172815;5409.350877894639;5409.459054094598;5475.616706830523;5835.4748072673665;5849.941500584982;5869.471747542699;5905.763769449217;5976.163337132805;6287.811365659038;6294.0873573332065;6295.213098362705;6312.936870631281;6315.336889626615;5424.783008679648;6243.531735119885;6299.748010079591;6341.239051313872;6364.077208804697;6057.42454466825;6074.149619053336;6112.022076467744;6121.755129794848;6140.933729673037;6421.101045853584;6442.549021568539;6446.387593178641;6451.258105317855;6470;5237.816676416307;6268.811935641938;6270.655113968684;6294.0558916162945;6308.779192728232;6280.403361680633;6348.809535713844;6350.5554611177695;6357.936420635827;6360.109584658151;6215.65813880241;6244.406781355764;6279.026750853625;6285.340039295808;6289.276733175652;6181.949068658339;6292.748290068437;6302.747890084345;6304.211973503312;6308.495320374359;4984.675996060317;5055.039542486916;5072.264521644341;5096.745162741848;5116.232565115262;4574.245249533789;4583.427071616048;4590.036092420629;4596.839110631112;4620.584147426681;6061.212042434543;6066;6068.2111325528285;6077.118817771398;6085.36103709113;5257.421683614799;6201.348858369806;6263.937360626335;6328.936710632926;6370.872582548323;6019.187409699722;6049.153118563405;6054.212952316171;6061.54538409621;6089.117078023662;5575.052241118939;5583.553315734732;5586.245856809279;5617.21359009739;5618.747156377891;6048.274207095089;6067.7876274331065;6377.7130029147875;6536.803895883131;6568.770093046721;6317.778877739257;6331.620102793847;6340.175777148941;6341.143945567289;6362.77730279438;6579.638119903451;6589.670516474253;6590.538662293593;6592.80221593353;6599.868002639824;6142.498775697809;6216.378362163929;6276.246850377801;6285.308616052139;6317.810465686037;6273.592216505913;6313.526485513513;6322.114903913548;6345.746170153327;6351.460125889166;6247.625142491465;6259;6267.561270711007;6268.7492500301305;6275.37246275387;6169.598976066542;6206.72069756871;6242.625442473466;6254.405831446005;6279.434850863444;5047.041062198171;5204.375474943031;5814.186013958134;6273.121762953281;6278.434940855345;6206.627602343874;6216.875662486635;6232.25212974446;6236.532260080603;6262.436380725757;6124.387561244018;6172.105044768394;6240.469560087444;6278.46633036197;6301.558890877778 08:05:49 INFO - PROCESS | 1674 | |1;ext.html;536.9463053694631;547.9095949168387;572.1018001737274;590.929088509379;606.4511616986623;26648.60027099595;38947;39852.00369990748;39862;40036.79981600095;37332.44001339982;44594.55405445945;44979.32531012037;45000;45055;8657.740267791945;9013.77465563363;9740.025997400275;9928.354656947311;9953.800923981516;11756.529738810481;12051.758964820698;12056.819147712791;12069;12072.939635301811;11748.53005879768;11802.173847830656;11822.704432389173;11837.940810295979;11855.407229638527;11820;11924.582639607615;11933.940330298294;11935.641930742091;11953.521859125625;11638.243514171636;11674.066074714003;11748.588799392066;11850.992665623346;11964.042876569854;41193.382099268536;41564.58435415668;41651.58348416507;41663.95840103991;41751.58248417507;3246.788958717676;3330.6169790474164;3641.3263546243766;3835.271298453285;3965.4844870166694;28153.57769633457;28599.857000714863;28696.85651571729;29079;29414.11757647274;2757.5587905935154;2774.5283301838735;2820.49231138395;2826.1097754207563;2831.306329949155;382707;383699.0815045935;384503;385356;387095;37575.62424375776;38373.23253534914;64531.677341612994;65196;65206.67396662986;36596;36923;36951;37019.629803702155;37133.628663713556;16043.839561604467;16230.918845405697;16235.188240587924;16266.67466650684;16279;12730.872691272969;13799.793003104962;13924.303784810922;14008.019438639401;14084.85915140856;8400.285975692012;8401.201885820838;8401.285890699359;8478.576071196416;8481.745547633582;3522.5949015863503;3537.858485660596;3539.911502212414;3542.1498840278146;3583.1400463889;984.4388698441962;997.6358629100471;1041.8072656558409;1062.3838173859144;1110.8444817725524;30328.54507182394;30564.54153187704;30620.566496353633;31204.531932021036;31650.841745791124;891.4116682989196;1080.90812280957;1223.1988047828686;1225.7364666596725;1255.0859938593535;11194.425282185142;13945.790813137812;14549.418023279162;14656.754175894956;16215;509981;629717.85141074;635556;636329;638399;62788.68605656942;63038.68480657567;63041.684791575746;63053;63178;1304.41301414362;1407.324484247567;1411.2520364206782;1547.1181426586738;1554.2694933381292;27445.45109097767;29288.853555732087;31742.841285793424;32047;32457.35085298328;53345.19982200348;53802.461975379745;54010.45989540054;54069;54152.72923635357;510508.4474577752;523482;529811;529814;530358;35088;36976;37054.66708890786;44649;45334.319985200906;41600.58399416088;43659.345109822745;43915.32811846867;44446.55553444424;44849.62894155944;34430;36727.63272367349;36868.446973295955;36915.446268306536;37976.81011595035;36037;36149.81925090358;36481.90554283376;36889.63110368862;36950.07624809401;37483.81258093692;38033.23933521369;38172.42741358937;38325.61674383221;38627.80686096664;32495.837520812245;32714.018579442654;33100.503492448115;33226.00321990344;33310.66689333173;25247.99008039658;25562.872185638953;25781.226563203134;26238.475230495664;26492.735072649026 08:05:49 INFO - PROCESS | 1674 | |2;jquery.html;348.2738490247833;410.4684433658412;411.3110539845758;415.1572308214324;422.45503300742047;15128.856986192124;21060.57878842424;21578.568428631417;21699.783002169974;21808.018639161237;21214.04536795844;21519.784802151975;22459.887700561474;22480.662790058122;22665;5825.1844741736195;5980.970095149518;6186.907196392058;6209.285932117797;6211.596246243979;6067.848303792396;6090.056041313582;6159.168512250833;6159.81520554384;6181.783637572686;5960.642361458326;6117.296510901258;6123.050927106284;6136.877262454748;6145.231846019247;5835.941640583582;6079.361667024965;6119.2350956130485;6153.138560601497;6191.380861913818;6025.126356678257;6036.3360030396625;6043.637381757108;6049.637021778707;6056.788012419544;13443.66390840227;13955.651108722263;14013.5095271665;14038.80670143039;14042.648933776738;2108.3569511299047;2188.3325585696357;2289.530646217538;2350.8824558771994;2681.570948648207;9895.356801807931;10573.471326433648;10891.400972946563;15404.075755454709;15514.379424822993;1706.3601149568913;1805.8916465012142;1851.833334999843;1893.933712320062;1907.9298960627737;96119;119958.80041199563;122010;122216;123033.38483307527;11655.650330489929;11937.045036397067;11944.522219111312;11984.760304793856;12006.279623222634;11672.533098675958;14542.927285363718;14556.272186390637;15505.534833955;15580.610484737972;8464.238218560358;8813.647454101892;8993.55032248385;9167.724968250963;9236.07639236071;7617.581033043236;9079.137481939206;9086.136817002374;9094.454332740057;9216.308776841764;4619.86140415788;4705.670603057753;4763.095011947719;4763.95236047642;4820.542048505386;1995.5410255641284;2027.4931267183204;2032.4004418696225;2037.724907137547;2068.5449201175716;970.112347202302;1010.8483727440796;1027.2809033676456;1055.7518983038979;1063.7500187455942;21116.155353785984;21659.783402166093;21706.34880953574;21808.45478863041;21877.67183492249;1002.5789168549081;1015.791762688647;1018.5976539267097;1027.465717826726;1070.9410982396043;9511.874984521603;11023.779524409467;11415.143864210217;11416.828747568794;11726.70683232909;584631;599436.0310182982;607132.9643351843;624127;629320;127750.36124819316;130648;131883.34058329646;132564;137531;1022.6318525330875;1030.4178139351243;1034.270839058462;1039.0648416425279;1054.0987455725399;18815.90592047031;18941.242350305834;19250.71123933114;19278.518037049187;19353.709694354315;52047;55213.72393138009;55431.445685542625;55528.722356389575;57830;69725.60548788894;77555;80380.59809700915;81444.11335319899;81887;7803;8359.540225287668;8432.241098301183;8464;8542.658293668184;7450.0687414073245;8291.95854020726;11771.587994420153;12849;13061.08572399923;11004.449777511252;11322.32066076038;11481.311121332747;11533.769324613628;11598.826017609574;11007.284526505751;11056.889431105586;11247.550097995989;11341.546338146713;11464.656060318204;11543.307401555934;11609.941950290195;11675.708107297045;11936.641900742992;11959.461824218106;8279.62741676638;8281.130481299377;8399.538025408661;8417.410781245255;8630.439021463584;8547.615357308812;10905.34567925927;11365.772684546097;11423.828642570203;11705.590304339306 08:05:49 INFO - PROCESS | 1674 | |3;mootools.html;1520.7870898074264;1622.310518029837;1653.503948815355;1896.7818700849407;1998.8201061904435;8423.494590324584;9217.81564368713;9345.252379809615;9354;9385.671501497449;8984.236339911098;9251.953740231289;9274.814503709922;9284.210842078432;9317.860232096507;2524.167024881789;2544.5547029269896;2547.9108231211912;2548.4393433444707;2553.642490051385;2524.2427271818474;2591.922242332733;2594.2347007632698;2598.7141414444395;2606.087869245758;2547.477767057758;2578.510083084208;2595.7014943281574;2605.218434469661;2611.556035473974;2559.413163838423;2579.9613005804927;2595.2473782603024;2601.9089331873292;2603.3491627093226;2369.170790223408;2428.761331720835;2538.8095892808115;2583.9483210335875;2591.7019542752637;8991.685291014848;9217.723468295895;9344.439333639935;9379.921309049478;9411.011843756376;5754.597178197528;5908.940910590925;5925.170476133345;5925.970370148121;5928.110783382484;3662.468942003421;3741.663250307485;3798.5441746990264;3804.752691075071;3809.580946095927;1048.6958781953224;1071.8499410082595;1074.5809134437575;1087.342157994418;1104.2546281260197;12404.317762523058;12596.68508287291;12599.811002834966;12614.306213158272;12622.053345999087;4218;4784.641151913551;4822.276658501253;4936.925946110811;4940.530649588283;9757.414555126574;9808.607655693835;9812.558434870449;9844.60621575129;9855.211583073336;5680.659160450304;5682.090865461589;5693.544516438675;5696.031674615326;5711.771529138788;4711;4763.571278584875;4816.34979277793;4823.662343635911;5332.893342133136;3797.7911214883447;3883.9611603884164;3897.6881849451975;4135.82129093207;4146.419501269825;1278.2458349573797;1288.3944546063335;1288.903332250085;1297.6820678933532;1300.1159211735921;926.3376685669643;968.2979839616222;980.2256217588168;996.1283876607968;1008.8940661230612;6060.363661815534;6157.538184636081;6166.876662466815;6206.503479721611;6344.1284820977;984.0995489127369;1006.9244806639385;1014.2241185493103;1021.3667526133696;1054.1092776603757;3969.8809035728973;4064.532578753422;4109.958900411018;4142.979285103555;4149.419081328617;11246.887531124748;11643.708907277216;11674.649760507198;11821.822672659917;11846.763064738829;11462.140339474572;11492.195546311677;11589.594364197214;11618.593349232737;11628.53485860573;8559.700410485602;8819.735407937773;8830.205281524564;8950.641974320954;8977.102289771101;5920.644761314335;5985.371535988658;6014.428629280212;6076.605020673642;6076.635401875901;10328.741781455523;10359.32664376813;10471;10525.684229473129;10537.683869483928;9853.211743060681;10186.286959912735;10364.326318789255;10679.679609611723;10843.512041957973;6969.989351544099;7437.776866694008;7865.842683146419;7932.920670793449;7990.121086680344;8584.098669639598;8629.007664118706;8631.6978905738;8649.697260595849;8653.17794809492;7556.471047026876;7686.154523002577;7734;7760.30157285847;7850.214978502104;7475.289847464483;7488.1763006068195;7556.055493063367;7618.314351708373;7689.154193038649;7280.817979550554;7353.8161545961775;7358.668859901424;7359.638466883644;7369.63151842395;4890.510948905081;4953.18272485036;4964.05682920244;5062.088824011713;5130.230465430215;5127.359080114986;5128.53843154103;5137.280780690631;5159.277701121771;5419.593530485308 08:05:49 INFO - PROCESS | 1674 | |4;prototype.html;332.3933820777082;338.4669146094901;341.6310384784432;347.1529468097843;349.93876071687447;6631.270560238376;6640.103586015886;6676;6716.227633822112;6732.225794033688;6614.437772789319;6631.237407698116;6641.6015039097565;6641.833954151149;6643.1031810705545;4711.034237981215;4788.592969597571;4856.39295088114;4862.124817532843;4901.8774530636665;4849.490803465621;4873.147199240137;4898.975505122469;4911.091448082117;4911.58251548617;4855.271709243606;4856.732879691615;4859.635527335464;4873.171560834667;4913.631477639174;4609.7695115244105;4729.101470720552;4873.805047798084;4877.244027175793;4926.655134140611;4163.979824942909;4176.331786914109;4181.853635122756;4185.22573323933;4204.768737719424;5053.722045287508;6234.532410069263;6241.438270555672;6254.6247225166635;6392.4886009119155;5820;5831.854203644901;5832.504237139849;5846.7953621623465;5851.063829787214;7093.893591596078;7102.360787529095;7111.3244241797465;7119.679614417312;7121.71513139479;4428.446444194476;4601.930971035437;4603.907921841578;4650.441946966352;4686.320483529903;7372.410207183412;7528.435367347471;7534.849303013909;7550.6979720811105;7552.320291173821;4684.5549672781035;4693.530646935279;4696.436427628706;4710.52894710533;4726.527347265314;4767.213409787346;4767.976160119178;4776.068666609977;4779;4779.187538118528;5657;5671.092625180034;5673.914891276634;5676.631018983753;5697.145428185722;6887.139107611549;7127.572345659277;7147.178074521393;7157.284271572905;7173.282671732889;1629.6414788746456;1644.761509581104;1652.7768751218432;1653.2395098254938;1654.487108996215;985.103555764251;1034.5758239121924;1039.2050081687514;1059.7721489879714;1060.6923992042375;5616.0171969905105;6468.8059358219325;6765;6792.592444453348;6803.319668033256;7488.925110748932;7489.13874904393;7499.662515186829;7499.962500187464;7512.774616761506;6731.182580703183;6736.326367363225;6738.764143254963;6747.966260168668;6758;3889.202713443737;3943.428202910562;3945.5204298388103;3947.4276229946495;3956.624120708528;6324.747010119636;7565.6973721050545;7820.530768153815;7834.529928204326;7838.725644602411;7627.389808815282;7695.268949449794;7713.961430192813;7813.296803287732;7819.218078192135;7904.367650587939;7910.5253684777945;7943.126256111823;7946.483478574106;7954.721584744506;6076.301225358963;7230.385417239593;7397.4082073435075;7518.736844210426;7542;7278.344948954407;7373;7378.667959941708;7391.889121663287;7392.926070739439;7881.290683838482;7888.802779930547;7891.210878912063;7892.289693927574;7897.68409263646;4058.736182148151;4722.669413141047;4731.243001119804;4734.881627959328;4735.12400205963;7206.459515536251;7432.777016689508;7451.031365922396;7470.813229669302;7470.991416158749;7160.319769621878;7206.315400036988;7215.025971493781;7218.639068046682;7230.349268565854;6271.717772700331;6753.3246675332075;6805.89791153143;7175.103112110986;7181.461390395846;6170.938290617216;7042.014118023583;7044.87282034872;7046.823829404306;7077.150741911004;6909.896351554831;7185.820354491179;7188.5327453715345;7193.856122877409;7202.747903823341;6783.219929708147;6903.206131294965;6909.170899492093;7060.54106483077;7082.610456424946 08:05:49 INFO - PROCESS | 1674 | |5;yui.html;717.5945590741231;747.0363231431454;769.8383339498705;784.7959530522063;799.0411506192567;14113.717725645482;15139.015963962349;15610.531684049469;15941.043537387735;15953.521394358158;14381;14503.98472106953;14519.346629401702;14558.126512409262;14574.562763117097;775.5695588948131;795.0578564401175;948.7390967483929;978.432509144698;981.175812317653;965.3966271080575;974.1038244814752;976.3117002513246;977.472165030886;978.5792109392958;826.995865020674;1030.9072183503483;1032.220673391592;1033.00315195836;1035.4512108582464;993.7913038261973;1034.9379037257752;1035.585765693721;1036.1037702387455;1037.7353774787457;765.0245936431095;765.9846803063963;767.4819496839667;768.9923100769032;771.2942657468466;15372.692546149014;15467.92266038674;15489;15505.844941550553;15507.689846203126;13187.934060329733;13483.595492135251;13511.864881351257;13520.932395338059;13791.172529648253;10452.895471045269;10516;10628.787424251548;10859;10911.236213465061;3641.107928557494;3659.8536058557625;3674.210044840372;3706.6664000240107;3860.7490513116836;84285;85305.57347213286;85623;85870.14129858684;86377.56811215967;10077.899221007696;10215.489225538842;10217.029382208679;10608.363498190134;10808.405537695502;23913.282601521983;23967.921443535084;24172;24238.030478780653;24273.029078837;7844.411669124838;7862.2137786222065;7877.566733829694;7892.802679932933;7926.524408535391;4102.118044620421;4151.481064866892;4193.245215861113;4197.97901010493;4237.936430953539;3790.1093243087853;3831.1571454280015;3844.000559854458;3848.3265428550453;3851.2490064437725;671.993280067193;688.5352387138762;692.9826754331182;694.3230350408412;703.1772825793834;527.9683219006872;586.5630105571377;607.8632307730725;610.2311088029027;660.3429587560408;8454.1123182065;8472.49165050099;8482.406231563855;8575.485470871768;8969.730908072768;589.2134001108483;597.3280059932575;612.2897438970773;641.9646919419476;643.0226056394255;3827.827747751302;3929.862454814068;3937.448757173998;4040.6565441937764;4195.58044195578;84827.5758621203;85931;94541.5272923631;94823.52588237014;94967;80198;80262.59868700619;80470;80672.59663701644;82020;67184.6640766793;67422.66288668623;71786.9231961521;72425;73024;6736.494762892705;7813.960930195312;7982.521048737094;8108.83782324362;8314.87527687073;50199.49800501948;50348.748256258485;50574.494255056976;51162.46512604628;51222.7194320145;55307.44692553023;64856.70286594147;67825.66087169532;67965;69497;10201.438920859127;10312.688362732484;10555.313904596176;10570.577176912837;11238.044766194966;10807.297525660806;10915.508802103766;11063.557457701601;11278.379689117177;11343.773124537629;10386.48067596602;10661.09380702649;11311.773764524827;11908;12138.514459421523;11553.537858485566;11569.247998880046;11642.70893227676;11832.645020649394;12312.199707019015;11157.944210278896;11251.774964500828;11269.774604508028;11549.826752598885;11562.826557601475;9818.75453113678;9845.60617575289;10050.447225402673;10129.746756331151;10204.948975255076;9863.506824658882;9928.354656947275;9929.503524823585;10037.799244014932;10089.344192627455 08:05:49 INFO - PROCESS | 1674 | __end_tp_report 08:05:49 INFO - PROCESS | 1674 | __start_cc_report 08:05:49 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1145 08:05:49 INFO - PROCESS | 1674 | __end_cc_report 08:05:49 INFO - PROCESS | 1674 | __startTimestamp1468595149729__endTimestamp 08:05:49 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | ------- Summary: start ------- 08:05:49 INFO - PROCESS | 1674 | Number of tests: 6 08:05:49 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5891.45 Median:6124.39 stddev:628.04 (10.3%) stddev-sans-first:600.47 08:05:49 INFO - PROCESS | 1674 | Values: 3466.9 3585.7 3598.6 3602.1 3682.5 5966.9 5990.2 6007.5 6016.5 6071.9 5990.7 6037.5 6049.1 6054.0 6064.9 5316.6 5362.1 5437.9 5441.5 5502.4 5383.6 5463.3 5464.0 5475.6 5478.2 5372.8 5436.6 5437.3 5446.9 5455.9 5458.1 5472.2 5498.2 5508.9 5511.8 4419.8 5356.1 5409.4 5409.5 5475.6 5835.5 5849.9 5869.5 5905.8 5976.2 6287.8 6294.1 6295.2 6312.9 6315.3 5424.8 6243.5 6299.7 6341.2 6364.1 6057.4 6074.1 6112.0 6121.8 6140.9 6421.1 6442.5 6446.4 6451.3 6470.0 5237.8 6268.8 6270.7 6294.1 6308.8 6280.4 6348.8 6350.6 6357.9 6360.1 6215.7 6244.4 6279.0 6285.3 6289.3 6181.9 6292.7 6302.7 6304.2 6308.5 4984.7 5055.0 5072.3 5096.7 5116.2 4574.2 4583.4 4590.0 4596.8 4620.6 6061.2 6066.0 6068.2 6077.1 6085.4 5257.4 6201.3 6263.9 6328.9 6370.9 6019.2 6049.2 6054.2 6061.5 6089.1 5575.1 5583.6 5586.2 5617.2 5618.7 6048.3 6067.8 6377.7 6536.8 6568.8 6317.8 6331.6 6340.2 6341.1 6362.8 6579.6 6589.7 6590.5 6592.8 6599.9 6142.5 6216.4 6276.2 6285.3 6317.8 6273.6 6313.5 6322.1 6345.7 6351.5 6247.6 6259.0 6267.6 6268.7 6275.4 6169.6 6206.7 6242.6 6254.4 6279.4 5047.0 5204.4 5814.2 6273.1 6278.4 6206.6 6216.9 6232.3 6236.5 6262.4 6124.4 6172.1 6240.5 6278.5 6301.6 08:05:49 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:64357.72 Median:36538.95 stddev:137702.84 (376.9%) stddev-sans-first:138005.76 08:05:49 INFO - PROCESS | 1674 | Values: 536.9 547.9 572.1 590.9 606.5 26648.6 38947.0 39852.0 39862.0 40036.8 37332.4 44594.6 44979.3 45000.0 45055.0 8657.7 9013.8 9740.0 9928.4 9953.8 11756.5 12051.8 12056.8 12069.0 12072.9 11748.5 11802.2 11822.7 11837.9 11855.4 11820.0 11924.6 11933.9 11935.6 11953.5 11638.2 11674.1 11748.6 11851.0 11964.0 41193.4 41564.6 41651.6 41664.0 41751.6 3246.8 3330.6 3641.3 3835.3 3965.5 28153.6 28599.9 28696.9 29079.0 29414.1 2757.6 2774.5 2820.5 2826.1 2831.3 382707.0 383699.1 384503.0 385356.0 387095.0 37575.6 38373.2 64531.7 65196.0 65206.7 36596.0 36923.0 36951.0 37019.6 37133.6 16043.8 16230.9 16235.2 16266.7 16279.0 12730.9 13799.8 13924.3 14008.0 14084.9 8400.3 8401.2 8401.3 8478.6 8481.7 3522.6 3537.9 3539.9 3542.1 3583.1 984.4 997.6 1041.8 1062.4 1110.8 30328.5 30564.5 30620.6 31204.5 31650.8 891.4 1080.9 1223.2 1225.7 1255.1 11194.4 13945.8 14549.4 14656.8 16215.0 509981.0 629717.9 635556.0 636329.0 638399.0 62788.7 63038.7 63041.7 63053.0 63178.0 1304.4 1407.3 1411.3 1547.1 1554.3 27445.5 29288.9 31742.8 32047.0 32457.4 53345.2 53802.5 54010.5 54069.0 54152.7 510508.4 523482.0 529811.0 529814.0 530358.0 35088.0 36976.0 37054.7 44649.0 45334.3 41600.6 43659.3 43915.3 44446.6 44849.6 34430.0 36727.6 36868.4 36915.4 37976.8 36037.0 36149.8 36481.9 36889.6 36950.1 37483.8 38033.2 38172.4 38325.6 38627.8 32495.8 32714.0 33100.5 33226.0 33310.7 25248.0 25562.9 25781.2 26238.5 26492.7 08:05:49 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:35360.18 Median:21679.78 stddev:101679.42 (469.0%) stddev-sans-first:101930.68 08:05:49 INFO - PROCESS | 1674 | Values: 348.3 410.5 411.3 415.2 422.5 15128.9 21060.6 21578.6 21699.8 21808.0 21214.0 21519.8 22459.9 22480.7 22665.0 5825.2 5981.0 6186.9 6209.3 6211.6 6067.8 6090.1 6159.2 6159.8 6181.8 5960.6 6117.3 6123.1 6136.9 6145.2 5835.9 6079.4 6119.2 6153.1 6191.4 6025.1 6036.3 6043.6 6049.6 6056.8 13443.7 13955.7 14013.5 14038.8 14042.6 2108.4 2188.3 2289.5 2350.9 2681.6 9895.4 10573.5 10891.4 15404.1 15514.4 1706.4 1805.9 1851.8 1893.9 1907.9 96119.0 119958.8 122010.0 122216.0 123033.4 11655.7 11937.0 11944.5 11984.8 12006.3 11672.5 14542.9 14556.3 15505.5 15580.6 8464.2 8813.6 8993.6 9167.7 9236.1 7617.6 9079.1 9086.1 9094.5 9216.3 4619.9 4705.7 4763.1 4764.0 4820.5 1995.5 2027.5 2032.4 2037.7 2068.5 970.1 1010.8 1027.3 1055.8 1063.8 21116.2 21659.8 21706.3 21808.5 21877.7 1002.6 1015.8 1018.6 1027.5 1070.9 9511.9 11023.8 11415.1 11416.8 11726.7 584631.0 599436.0 607133.0 624127.0 629320.0 127750.4 130648.0 131883.3 132564.0 137531.0 1022.6 1030.4 1034.3 1039.1 1054.1 18815.9 18941.2 19250.7 19278.5 19353.7 52047.0 55213.7 55431.4 55528.7 57830.0 69725.6 77555.0 80380.6 81444.1 81887.0 7803.0 8359.5 8432.2 8464.0 8542.7 7450.1 8292.0 11771.6 12849.0 13061.1 11004.4 11322.3 11481.3 11533.8 11598.8 11007.3 11056.9 11247.6 11341.5 11464.7 11543.3 11609.9 11675.7 11936.6 11959.5 8279.6 8281.1 8399.5 8417.4 8630.4 8547.6 10905.3 11365.8 11423.8 11705.6 08:05:49 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:6003.13 Median:4857.09 stddev:3372.49 (69.4%) stddev-sans-first:3365.13 08:05:49 INFO - PROCESS | 1674 | Values: 1520.8 1622.3 1653.5 1896.8 1998.8 8423.5 9217.8 9345.3 9354.0 9385.7 8984.2 9252.0 9274.8 9284.2 9317.9 2524.2 2544.6 2547.9 2548.4 2553.6 2524.2 2591.9 2594.2 2598.7 2606.1 2547.5 2578.5 2595.7 2605.2 2611.6 2559.4 2580.0 2595.2 2601.9 2603.3 2369.2 2428.8 2538.8 2583.9 2591.7 8991.7 9217.7 9344.4 9379.9 9411.0 5754.6 5908.9 5925.2 5926.0 5928.1 3662.5 3741.7 3798.5 3804.8 3809.6 1048.7 1071.8 1074.6 1087.3 1104.3 12404.3 12596.7 12599.8 12614.3 12622.1 4218.0 4784.6 4822.3 4936.9 4940.5 9757.4 9808.6 9812.6 9844.6 9855.2 5680.7 5682.1 5693.5 5696.0 5711.8 4711.0 4763.6 4816.3 4823.7 5332.9 3797.8 3884.0 3897.7 4135.8 4146.4 1278.2 1288.4 1288.9 1297.7 1300.1 926.3 968.3 980.2 996.1 1008.9 6060.4 6157.5 6166.9 6206.5 6344.1 984.1 1006.9 1014.2 1021.4 1054.1 3969.9 4064.5 4110.0 4143.0 4149.4 11246.9 11643.7 11674.6 11821.8 11846.8 11462.1 11492.2 11589.6 11618.6 11628.5 8559.7 8819.7 8830.2 8950.6 8977.1 5920.6 5985.4 6014.4 6076.6 6076.6 10328.7 10359.3 10471.0 10525.7 10537.7 9853.2 10186.3 10364.3 10679.7 10843.5 6970.0 7437.8 7865.8 7932.9 7990.1 8584.1 8629.0 8631.7 8649.7 8653.2 7556.5 7686.2 7734.0 7760.3 7850.2 7475.3 7488.2 7556.1 7618.3 7689.2 7280.8 7353.8 7358.7 7359.6 7369.6 4890.5 4953.2 4964.1 5062.1 5130.2 5127.4 5128.5 5137.3 5159.3 5419.6 08:05:49 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:5846.22 Median:6642.47 stddev:1889.26 (28.4%) stddev-sans-first:1848.67 08:05:49 INFO - PROCESS | 1674 | Values: 332.4 338.5 341.6 347.2 349.9 6631.3 6640.1 6676.0 6716.2 6732.2 6614.4 6631.2 6641.6 6641.8 6643.1 4711.0 4788.6 4856.4 4862.1 4901.9 4849.5 4873.1 4899.0 4911.1 4911.6 4855.3 4856.7 4859.6 4873.2 4913.6 4609.8 4729.1 4873.8 4877.2 4926.7 4164.0 4176.3 4181.9 4185.2 4204.8 5053.7 6234.5 6241.4 6254.6 6392.5 5820.0 5831.9 5832.5 5846.8 5851.1 7093.9 7102.4 7111.3 7119.7 7121.7 4428.4 4601.9 4603.9 4650.4 4686.3 7372.4 7528.4 7534.8 7550.7 7552.3 4684.6 4693.5 4696.4 4710.5 4726.5 4767.2 4768.0 4776.1 4779.0 4779.2 5657.0 5671.1 5673.9 5676.6 5697.1 6887.1 7127.6 7147.2 7157.3 7173.3 1629.6 1644.8 1652.8 1653.2 1654.5 985.1 1034.6 1039.2 1059.8 1060.7 5616.0 6468.8 6765.0 6792.6 6803.3 7488.9 7489.1 7499.7 7500.0 7512.8 6731.2 6736.3 6738.8 6748.0 6758.0 3889.2 3943.4 3945.5 3947.4 3956.6 6324.7 7565.7 7820.5 7834.5 7838.7 7627.4 7695.3 7714.0 7813.3 7819.2 7904.4 7910.5 7943.1 7946.5 7954.7 6076.3 7230.4 7397.4 7518.7 7542.0 7278.3 7373.0 7378.7 7391.9 7392.9 7881.3 7888.8 7891.2 7892.3 7897.7 4058.7 4722.7 4731.2 4734.9 4735.1 7206.5 7432.8 7451.0 7470.8 7471.0 7160.3 7206.3 7215.0 7218.6 7230.3 6271.7 6753.3 6805.9 7175.1 7181.5 6170.9 7042.0 7044.9 7046.8 7077.2 6909.9 7185.8 7188.5 7193.9 7202.7 6783.2 6903.2 6909.2 7060.5 7082.6 08:05:49 INFO - PROCESS | 1674 | 08:05:49 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:18631.85 Median:4196.78 stddev:25995.93 (619.4%) stddev-sans-first:26034.05 08:05:49 INFO - PROCESS | 1674 | Values: 717.6 747.0 769.8 784.8 799.0 14113.7 15139.0 15610.5 15941.0 15953.5 14381.0 14504.0 14519.3 14558.1 14574.6 775.6 795.1 948.7 978.4 981.2 965.4 974.1 976.3 977.5 978.6 827.0 1030.9 1032.2 1033.0 1035.5 993.8 1034.9 1035.6 1036.1 1037.7 765.0 766.0 767.5 769.0 771.3 15372.7 15467.9 15489.0 15505.8 15507.7 13187.9 13483.6 13511.9 13520.9 13791.2 10452.9 10516.0 10628.8 10859.0 10911.2 3641.1 3659.9 3674.2 3706.7 3860.7 84285.0 85305.6 85623.0 85870.1 86377.6 10077.9 10215.5 10217.0 10608.4 10808.4 23913.3 23967.9 24172.0 24238.0 24273.0 7844.4 7862.2 7877.6 7892.8 7926.5 4102.1 4151.5 4193.2 4198.0 4237.9 3790.1 3831.2 3844.0 3848.3 3851.2 672.0 688.5 693.0 694.3 703.2 528.0 586.6 607.9 610.2 660.3 8454.1 8472.5 8482.4 8575.5 8969.7 589.2 597.3 612.3 642.0 643.0 3827.8 3929.9 3937.4 4040.7 4195.6 84827.6 85931.0 94541.5 94823.5 94967.0 80198.0 80262.6 80470.0 80672.6 82020.0 67184.7 67422.7 71786.9 72425.0 73024.0 6736.5 7814.0 7982.5 8108.8 8314.9 50199.5 50348.7 50574.5 51162.5 51222.7 55307.4 64856.7 67825.7 67965.0 69497.0 10201.4 10312.7 10555.3 10570.6 11238.0 10807.3 10915.5 11063.6 11278.4 11343.8 10386.5 10661.1 11311.8 11908.0 12138.5 11553.5 11569.2 11642.7 11832.6 12312.2 11157.9 11251.8 11269.8 11549.8 11562.8 9818.8 9845.6 10050.4 10129.7 10204.9 9863.5 9928.4 9929.5 10037.8 10089.3 08:05:49 INFO - PROCESS | 1674 | -------- Summary: end -------- 08:05:49 INFO - PROCESS | 1674 | 08:05:50 INFO - TEST-INFO | 1674: exit 0 08:05:50 INFO - TEST-OK | dromaeo_css | took 1113291ms 08:05:50 INFO - TEST-START | kraken 08:05:50 INFO - operating with platform_type : mac_ 08:05:50 INFO - Initialising browser for kraken test... 08:05:50 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmps_4uIw/profile http://localhost:49283/getInfo.html) 08:05:52 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 08:05:52 INFO - PROCESS | 1682 | colorDepth:24 08:05:52 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 08:05:52 INFO - PROCESS | 1682 | __metrics 08:05:52 INFO - PROCESS | 1682 | 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" 08:05:52 ERROR - PROCESS | 1682 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:05:53 INFO - TEST-INFO | 1682: exit 0 08:05:53 INFO - Browser initialized. 08:05:53 INFO - Running cycle 1/1 for kraken test... 08:05:53 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmps_4uIw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:06:05 INFO - PROCESS | 1685 | RSS: Main: 160456704 08:06:05 INFO - PROCESS | 1685 | 08:06:07 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 08:06:08 INFO - PROCESS | 1685 | RSS: Main: 224231424 08:06:08 INFO - PROCESS | 1685 | 08:06:11 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 08:06:11 INFO - PROCESS | 1685 | RSS: Main: 240291840 08:06:11 INFO - PROCESS | 1685 | 08:06:14 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 08:06:15 INFO - PROCESS | 1685 | RSS: Main: 324534272 08:06:15 INFO - PROCESS | 1685 | 08:06:17 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 08:06:18 INFO - PROCESS | 1685 | RSS: Main: 231792640 08:06:18 INFO - PROCESS | 1685 | 08:06:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:06:20 INFO - PROCESS | 1685 | RSS: Main: 232255488 08:06:20 INFO - PROCESS | 1685 | 08:06:23 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 08:06:24 INFO - PROCESS | 1685 | RSS: Main: 268849152 08:06:24 INFO - PROCESS | 1685 | 08:06:27 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 08:06:27 INFO - PROCESS | 1685 | RSS: Main: 269443072 08:06:27 INFO - PROCESS | 1685 | 08:06:30 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 08:06:31 INFO - PROCESS | 1685 | RSS: Main: 269418496 08:06:31 INFO - PROCESS | 1685 | 08:06:32 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:06:33 INFO - PROCESS | 1685 | RSS: Main: 261103616 08:06:33 INFO - PROCESS | 1685 | 08:06:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:06:35 INFO - PROCESS | 1685 | RSS: Main: 268111872 08:06:35 INFO - PROCESS | 1685 | 08:06:37 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:06:38 INFO - PROCESS | 1685 | RSS: Main: 271540224 08:06:38 INFO - PROCESS | 1685 | 08:06:41 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:06:42 INFO - PROCESS | 1685 | RSS: Main: 278466560 08:06:42 INFO - PROCESS | 1685 | 08:06:44 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:06:45 INFO - PROCESS | 1685 | RSS: Main: 275533824 08:06:45 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 08:06:47 INFO - PROCESS | 1685 | RSS: Main: 336162816 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | __start_tp_report 08:06:47 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 08:06:47 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 08:06:47 INFO - PROCESS | 1685 | |i|pagename|runs| 08:06:47 INFO - PROCESS | 1685 | |0;ai-astar;114;112;120;111;111;112;112;112;113;135 08:06:47 INFO - PROCESS | 1685 | |1;audio-beat-detection;155;116;141;161;118;136;119;151;152;118 08:06:47 INFO - PROCESS | 1685 | |2;audio-dft;186;184;184;184;186;185;185;183;184;184 08:06:47 INFO - PROCESS | 1685 | |3;audio-fft;69;79;73;150;57;55;58;61;129;54 08:06:47 INFO - PROCESS | 1685 | |4;audio-oscillator;90;91;140;80;89;86;125;79;77;155 08:06:47 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;88;85;88;86;88;86;126;128;117;84 08:06:47 INFO - PROCESS | 1685 | |6;imaging-darkroom;93;94;104;131;87;131;94;93;93;93 08:06:47 INFO - PROCESS | 1685 | |7;imaging-desaturate;86;100;139;141;86;86;85;86;85;86 08:06:47 INFO - PROCESS | 1685 | |8;json-parse-financial;60;62;63;64;64;60;62;77;58;56 08:06:47 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;60;61;58;74;74;77;68;66;65;66 08:06:47 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;79;76;80;79;79;78;78;78;79;82 08:06:47 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;128;127;124;129;144;156;143;112;168;165 08:06:47 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;164;166;163;171;167;162;162;161;173;161 08:06:47 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;66;68;66;66;67;66;67;65;66;66 08:06:47 INFO - PROCESS | 1685 | __end_tp_report 08:06:47 INFO - PROCESS | 1685 | __start_cc_report 08:06:47 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1901 08:06:47 INFO - PROCESS | 1685 | __end_cc_report 08:06:47 INFO - PROCESS | 1685 | __startTimestamp1468595207454__endTimestamp 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | ------- Summary: start ------- 08:06:47 INFO - PROCESS | 1685 | Number of tests: 14 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:115.20 Median:112.50 stddev:7.44 (6.6%) stddev-sans-first:7.87 08:06:47 INFO - PROCESS | 1685 | Values: 114.0 112.0 120.0 111.0 111.0 112.0 112.0 112.0 113.0 135.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:146.00 stddev:17.71 (12.1%) stddev-sans-first:17.51 08:06:47 INFO - PROCESS | 1685 | Values: 155.0 116.0 141.0 161.0 118.0 136.0 119.0 151.0 152.0 118.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:0.97 (0.5%) stddev-sans-first:0.87 08:06:47 INFO - PROCESS | 1685 | Values: 186.0 184.0 184.0 184.0 186.0 185.0 185.0 183.0 184.0 184.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:78.50 Median:59.50 stddev:33.54 (56.4%) stddev-sans-first:35.40 08:06:47 INFO - PROCESS | 1685 | Values: 69.0 79.0 73.0 150.0 57.0 55.0 58.0 61.0 129.0 54.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:101.20 Median:83.00 stddev:28.09 (33.8%) stddev-sans-first:29.50 08:06:47 INFO - PROCESS | 1685 | Values: 90.0 91.0 140.0 80.0 89.0 86.0 125.0 79.0 77.0 155.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:97.60 Median:86.00 stddev:18.25 (21.2%) stddev-sans-first:19.02 08:06:47 INFO - PROCESS | 1685 | Values: 88.0 85.0 88.0 86.0 88.0 86.0 126.0 128.0 117.0 84.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:93.00 stddev:16.19 (17.4%) stddev-sans-first:16.89 08:06:47 INFO - PROCESS | 1685 | Values: 93.0 94.0 104.0 131.0 87.0 131.0 94.0 93.0 93.0 93.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:86.00 stddev:22.59 (26.3%) stddev-sans-first:23.54 08:06:47 INFO - PROCESS | 1685 | Values: 86.0 100.0 139.0 141.0 86.0 86.0 85.0 86.0 85.0 86.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:62.50 stddev:5.68 (9.1%) stddev-sans-first:5.95 08:06:47 INFO - PROCESS | 1685 | Values: 60.0 62.0 63.0 64.0 64.0 60.0 62.0 77.0 58.0 56.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:6.42 (9.6%) stddev-sans-first:6.30 08:06:47 INFO - PROCESS | 1685 | Values: 60.0 61.0 58.0 74.0 74.0 77.0 68.0 66.0 65.0 66.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.00 stddev:1.55 (2.0%) stddev-sans-first:1.64 08:06:47 INFO - PROCESS | 1685 | Values: 79.0 76.0 80.0 79.0 79.0 78.0 78.0 78.0 79.0 82.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:143.50 stddev:18.75 (13.1%) stddev-sans-first:19.41 08:06:47 INFO - PROCESS | 1685 | Values: 128.0 127.0 124.0 129.0 144.0 156.0 143.0 112.0 168.0 165.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.00 stddev:4.22 (2.6%) stddev-sans-first:4.46 08:06:47 INFO - PROCESS | 1685 | Values: 164.0 166.0 163.0 171.0 167.0 162.0 162.0 161.0 173.0 161.0 08:06:47 INFO - PROCESS | 1685 | 08:06:47 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 08:06:47 INFO - PROCESS | 1685 | Values: 66.0 68.0 66.0 66.0 67.0 66.0 67.0 65.0 66.0 66.0 08:06:47 INFO - PROCESS | 1685 | -------- Summary: end -------- 08:06:47 INFO - PROCESS | 1685 | 08:06:48 INFO - TEST-INFO | 1685: exit 0 08:06:48 INFO - TEST-OK | kraken | took 57921ms 08:06:48 INFO - SUITE-END | took 1171s 08:06:48 INFO - Completed test suite (00:19:31) 08:06:48 INFO - javascript benchmark 08:06:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3466.878659246926, 3585.6952159066477, 3598.60415354311, 3602.081469225347, 3682.4660424238486, 5966.940330596693, 5990.1913241712355, 6007.5494337924665, 6016.51867850572, 6071.878562428754, 5990.730417131229, 6037.516998640109, 6049.092636104587, 6054.0010898201745, 6064.878702425949, 5316.574674026088, 5362.115250983593, 5437.918431223535, 5441.5102640762325, 5502.367227768797, 5383.623146379755, 5463.31708536433, 5463.972680136594, 5475.616706830523, 5478.233047373371, 5372.838814835562, 5436.592255580827, 5437.293151890249, 5446.891062178754, 5455.890882182354, 5458.099413596771, 5472.2338872557475, 5498.202760599731, 5508.862278443031, 5511.834644960658, 4419.779011049434, 5356.1430171172815, 5409.350877894639, 5409.459054094598, 5475.616706830523, 5835.4748072673665, 5849.941500584982, 5869.471747542699, 5905.763769449217, 5976.163337132805, 6287.811365659038, 6294.0873573332065, 6295.213098362705, 6312.936870631281, 6315.336889626615, 5424.783008679648, 6243.531735119885, 6299.748010079591, 6341.239051313872, 6364.077208804697, 6057.42454466825, 6074.149619053336, 6112.022076467744, 6121.755129794848, 6140.933729673037, 6421.101045853584, 6442.549021568539, 6446.387593178641, 6451.258105317855, 6470.0, 5237.816676416307, 6268.811935641938, 6270.655113968684, 6294.0558916162945, 6308.779192728232, 6280.403361680633, 6348.809535713844, 6350.5554611177695, 6357.936420635827, 6360.109584658151, 6215.65813880241, 6244.406781355764, 6279.026750853625, 6285.340039295808, 6289.276733175652, 6181.949068658339, 6292.748290068437, 6302.747890084345, 6304.211973503312, 6308.495320374359, 4984.675996060317, 5055.039542486916, 5072.264521644341, 5096.745162741848, 5116.232565115262, 4574.245249533789, 4583.427071616048, 4590.036092420629, 4596.839110631112, 4620.584147426681, 6061.212042434543, 6066.0, 6068.2111325528285, 6077.118817771398, 6085.36103709113, 5257.421683614799, 6201.348858369806, 6263.937360626335, 6328.936710632926, 6370.872582548323, 6019.187409699722, 6049.153118563405, 6054.212952316171, 6061.54538409621, 6089.117078023662, 5575.052241118939, 5583.553315734732, 5586.245856809279, 5617.21359009739, 5618.747156377891, 6048.274207095089, 6067.7876274331065, 6377.7130029147875, 6536.803895883131, 6568.770093046721, 6317.778877739257, 6331.620102793847, 6340.175777148941, 6341.143945567289, 6362.77730279438, 6579.638119903451, 6589.670516474253, 6590.538662293593, 6592.80221593353, 6599.868002639824, 6142.498775697809, 6216.378362163929, 6276.246850377801, 6285.308616052139, 6317.810465686037, 6273.592216505913, 6313.526485513513, 6322.114903913548, 6345.746170153327, 6351.460125889166, 6247.625142491465, 6259.0, 6267.561270711007, 6268.7492500301305, 6275.37246275387, 6169.598976066542, 6206.72069756871, 6242.625442473466, 6254.405831446005, 6279.434850863444, 5047.041062198171, 5204.375474943031, 5814.186013958134, 6273.121762953281, 6278.434940855345, 6206.627602343874, 6216.875662486635, 6232.25212974446, 6236.532260080603, 6262.436380725757, 6124.387561244018, 6172.105044768394, 6240.469560087444, 6278.46633036197, 6301.558890877778], "value": 5854.940445566062, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.9463053694631, 547.9095949168387, 572.1018001737274, 590.929088509379, 606.4511616986623, 26648.60027099595, 38947.0, 39852.00369990748, 39862.0, 40036.79981600095, 37332.44001339982, 44594.55405445945, 44979.32531012037, 45000.0, 45055.0, 8657.740267791945, 9013.77465563363, 9740.025997400275, 9928.354656947311, 9953.800923981516, 11756.529738810481, 12051.758964820698, 12056.819147712791, 12069.0, 12072.939635301811, 11748.53005879768, 11802.173847830656, 11822.704432389173, 11837.940810295979, 11855.407229638527, 11820.0, 11924.582639607615, 11933.940330298294, 11935.641930742091, 11953.521859125625, 11638.243514171636, 11674.066074714003, 11748.588799392066, 11850.992665623346, 11964.042876569854, 41193.382099268536, 41564.58435415668, 41651.58348416507, 41663.95840103991, 41751.58248417507, 3246.788958717676, 3330.6169790474164, 3641.3263546243766, 3835.271298453285, 3965.4844870166694, 28153.57769633457, 28599.857000714863, 28696.85651571729, 29079.0, 29414.11757647274, 2757.5587905935154, 2774.5283301838735, 2820.49231138395, 2826.1097754207563, 2831.306329949155, 382707.0, 383699.0815045935, 384503.0, 385356.0, 387095.0, 37575.62424375776, 38373.23253534914, 64531.677341612994, 65196.0, 65206.67396662986, 36596.0, 36923.0, 36951.0, 37019.629803702155, 37133.628663713556, 16043.839561604467, 16230.918845405697, 16235.188240587924, 16266.67466650684, 16279.0, 12730.872691272969, 13799.793003104962, 13924.303784810922, 14008.019438639401, 14084.85915140856, 8400.285975692012, 8401.201885820838, 8401.285890699359, 8478.576071196416, 8481.745547633582, 3522.5949015863503, 3537.858485660596, 3539.911502212414, 3542.1498840278146, 3583.1400463889, 984.4388698441962, 997.6358629100471, 1041.8072656558409, 1062.3838173859144, 1110.8444817725524, 30328.54507182394, 30564.54153187704, 30620.566496353633, 31204.531932021036, 31650.841745791124, 891.4116682989196, 1080.90812280957, 1223.1988047828686, 1225.7364666596725, 1255.0859938593535, 11194.425282185142, 13945.790813137812, 14549.418023279162, 14656.754175894956, 16215.0, 509981.0, 629717.85141074, 635556.0, 636329.0, 638399.0, 62788.68605656942, 63038.68480657567, 63041.684791575746, 63053.0, 63178.0, 1304.41301414362, 1407.324484247567, 1411.2520364206782, 1547.1181426586738, 1554.2694933381292, 27445.45109097767, 29288.853555732087, 31742.841285793424, 32047.0, 32457.35085298328, 53345.19982200348, 53802.461975379745, 54010.45989540054, 54069.0, 54152.72923635357, 510508.4474577752, 523482.0, 529811.0, 529814.0, 530358.0, 35088.0, 36976.0, 37054.66708890786, 44649.0, 45334.319985200906, 41600.58399416088, 43659.345109822745, 43915.32811846867, 44446.55553444424, 44849.62894155944, 34430.0, 36727.63272367349, 36868.446973295955, 36915.446268306536, 37976.81011595035, 36037.0, 36149.81925090358, 36481.90554283376, 36889.63110368862, 36950.07624809401, 37483.81258093692, 38033.23933521369, 38172.42741358937, 38325.61674383221, 38627.80686096664, 32495.837520812245, 32714.018579442654, 33100.503492448115, 33226.00321990344, 33310.66689333173, 25247.99008039658, 25562.872185638953, 25781.226563203134, 26238.475230495664, 26492.735072649026], "value": 19326.107072426, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.2738490247833, 410.4684433658412, 411.3110539845758, 415.1572308214324, 422.45503300742047, 15128.856986192124, 21060.57878842424, 21578.568428631417, 21699.783002169974, 21808.018639161237, 21214.04536795844, 21519.784802151975, 22459.887700561474, 22480.662790058122, 22665.0, 5825.1844741736195, 5980.970095149518, 6186.907196392058, 6209.285932117797, 6211.596246243979, 6067.848303792396, 6090.056041313582, 6159.168512250833, 6159.81520554384, 6181.783637572686, 5960.642361458326, 6117.296510901258, 6123.050927106284, 6136.877262454748, 6145.231846019247, 5835.941640583582, 6079.361667024965, 6119.2350956130485, 6153.138560601497, 6191.380861913818, 6025.126356678257, 6036.3360030396625, 6043.637381757108, 6049.637021778707, 6056.788012419544, 13443.66390840227, 13955.651108722263, 14013.5095271665, 14038.80670143039, 14042.648933776738, 2108.3569511299047, 2188.3325585696357, 2289.530646217538, 2350.8824558771994, 2681.570948648207, 9895.356801807931, 10573.471326433648, 10891.400972946563, 15404.075755454709, 15514.379424822993, 1706.3601149568913, 1805.8916465012142, 1851.833334999843, 1893.933712320062, 1907.9298960627737, 96119.0, 119958.80041199563, 122010.0, 122216.0, 123033.38483307527, 11655.650330489929, 11937.045036397067, 11944.522219111312, 11984.760304793856, 12006.279623222634, 11672.533098675958, 14542.927285363718, 14556.272186390637, 15505.534833955, 15580.610484737972, 8464.238218560358, 8813.647454101892, 8993.55032248385, 9167.724968250963, 9236.07639236071, 7617.581033043236, 9079.137481939206, 9086.136817002374, 9094.454332740057, 9216.308776841764, 4619.86140415788, 4705.670603057753, 4763.095011947719, 4763.95236047642, 4820.542048505386, 1995.5410255641284, 2027.4931267183204, 2032.4004418696225, 2037.724907137547, 2068.5449201175716, 970.112347202302, 1010.8483727440796, 1027.2809033676456, 1055.7518983038979, 1063.7500187455942, 21116.155353785984, 21659.783402166093, 21706.34880953574, 21808.45478863041, 21877.67183492249, 1002.5789168549081, 1015.791762688647, 1018.5976539267097, 1027.465717826726, 1070.9410982396043, 9511.874984521603, 11023.779524409467, 11415.143864210217, 11416.828747568794, 11726.70683232909, 584631.0, 599436.0310182982, 607132.9643351843, 624127.0, 629320.0, 127750.36124819316, 130648.0, 131883.34058329646, 132564.0, 137531.0, 1022.6318525330875, 1030.4178139351243, 1034.270839058462, 1039.0648416425279, 1054.0987455725399, 18815.90592047031, 18941.242350305834, 19250.71123933114, 19278.518037049187, 19353.709694354315, 52047.0, 55213.72393138009, 55431.445685542625, 55528.722356389575, 57830.0, 69725.60548788894, 77555.0, 80380.59809700915, 81444.11335319899, 81887.0, 7803.0, 8359.540225287668, 8432.241098301183, 8464.0, 8542.658293668184, 7450.0687414073245, 8291.95854020726, 11771.587994420153, 12849.0, 13061.08572399923, 11004.449777511252, 11322.32066076038, 11481.311121332747, 11533.769324613628, 11598.826017609574, 11007.284526505751, 11056.889431105586, 11247.550097995989, 11341.546338146713, 11464.656060318204, 11543.307401555934, 11609.941950290195, 11675.708107297045, 11936.641900742992, 11959.461824218106, 8279.62741676638, 8281.130481299377, 8399.538025408661, 8417.410781245255, 8630.439021463584, 8547.615357308812, 10905.34567925927, 11365.772684546097, 11423.828642570203, 11705.590304339306], "value": 9713.819823373651, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1520.7870898074264, 1622.310518029837, 1653.503948815355, 1896.7818700849407, 1998.8201061904435, 8423.494590324584, 9217.81564368713, 9345.252379809615, 9354.0, 9385.671501497449, 8984.236339911098, 9251.953740231289, 9274.814503709922, 9284.210842078432, 9317.860232096507, 2524.167024881789, 2544.5547029269896, 2547.9108231211912, 2548.4393433444707, 2553.642490051385, 2524.2427271818474, 2591.922242332733, 2594.2347007632698, 2598.7141414444395, 2606.087869245758, 2547.477767057758, 2578.510083084208, 2595.7014943281574, 2605.218434469661, 2611.556035473974, 2559.413163838423, 2579.9613005804927, 2595.2473782603024, 2601.9089331873292, 2603.3491627093226, 2369.170790223408, 2428.761331720835, 2538.8095892808115, 2583.9483210335875, 2591.7019542752637, 8991.685291014848, 9217.723468295895, 9344.439333639935, 9379.921309049478, 9411.011843756376, 5754.597178197528, 5908.940910590925, 5925.170476133345, 5925.970370148121, 5928.110783382484, 3662.468942003421, 3741.663250307485, 3798.5441746990264, 3804.752691075071, 3809.580946095927, 1048.6958781953224, 1071.8499410082595, 1074.5809134437575, 1087.342157994418, 1104.2546281260197, 12404.317762523058, 12596.68508287291, 12599.811002834966, 12614.306213158272, 12622.053345999087, 4218.0, 4784.641151913551, 4822.276658501253, 4936.925946110811, 4940.530649588283, 9757.414555126574, 9808.607655693835, 9812.558434870449, 9844.60621575129, 9855.211583073336, 5680.659160450304, 5682.090865461589, 5693.544516438675, 5696.031674615326, 5711.771529138788, 4711.0, 4763.571278584875, 4816.34979277793, 4823.662343635911, 5332.893342133136, 3797.7911214883447, 3883.9611603884164, 3897.6881849451975, 4135.82129093207, 4146.419501269825, 1278.2458349573797, 1288.3944546063335, 1288.903332250085, 1297.6820678933532, 1300.1159211735921, 926.3376685669643, 968.2979839616222, 980.2256217588168, 996.1283876607968, 1008.8940661230612, 6060.363661815534, 6157.538184636081, 6166.876662466815, 6206.503479721611, 6344.1284820977, 984.0995489127369, 1006.9244806639385, 1014.2241185493103, 1021.3667526133696, 1054.1092776603757, 3969.8809035728973, 4064.532578753422, 4109.958900411018, 4142.979285103555, 4149.419081328617, 11246.887531124748, 11643.708907277216, 11674.649760507198, 11821.822672659917, 11846.763064738829, 11462.140339474572, 11492.195546311677, 11589.594364197214, 11618.593349232737, 11628.53485860573, 8559.700410485602, 8819.735407937773, 8830.205281524564, 8950.641974320954, 8977.102289771101, 5920.644761314335, 5985.371535988658, 6014.428629280212, 6076.605020673642, 6076.635401875901, 10328.741781455523, 10359.32664376813, 10471.0, 10525.684229473129, 10537.683869483928, 9853.211743060681, 10186.286959912735, 10364.326318789255, 10679.679609611723, 10843.512041957973, 6969.989351544099, 7437.776866694008, 7865.842683146419, 7932.920670793449, 7990.121086680344, 8584.098669639598, 8629.007664118706, 8631.6978905738, 8649.697260595849, 8653.17794809492, 7556.471047026876, 7686.154523002577, 7734.0, 7760.30157285847, 7850.214978502104, 7475.289847464483, 7488.1763006068195, 7556.055493063367, 7618.314351708373, 7689.154193038649, 7280.817979550554, 7353.8161545961775, 7358.668859901424, 7359.638466883644, 7369.63151842395, 4890.510948905081, 4953.18272485036, 4964.05682920244, 5062.088824011713, 5130.230465430215, 5127.359080114986, 5128.53843154103, 5137.280780690631, 5159.277701121771, 5419.593530485308], "value": 4832.513128469381, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.3933820777082, 338.4669146094901, 341.6310384784432, 347.1529468097843, 349.93876071687447, 6631.270560238376, 6640.103586015886, 6676.0, 6716.227633822112, 6732.225794033688, 6614.437772789319, 6631.237407698116, 6641.6015039097565, 6641.833954151149, 6643.1031810705545, 4711.034237981215, 4788.592969597571, 4856.39295088114, 4862.124817532843, 4901.8774530636665, 4849.490803465621, 4873.147199240137, 4898.975505122469, 4911.091448082117, 4911.58251548617, 4855.271709243606, 4856.732879691615, 4859.635527335464, 4873.171560834667, 4913.631477639174, 4609.7695115244105, 4729.101470720552, 4873.805047798084, 4877.244027175793, 4926.655134140611, 4163.979824942909, 4176.331786914109, 4181.853635122756, 4185.22573323933, 4204.768737719424, 5053.722045287508, 6234.532410069263, 6241.438270555672, 6254.6247225166635, 6392.4886009119155, 5820.0, 5831.854203644901, 5832.504237139849, 5846.7953621623465, 5851.063829787214, 7093.893591596078, 7102.360787529095, 7111.3244241797465, 7119.679614417312, 7121.71513139479, 4428.446444194476, 4601.930971035437, 4603.907921841578, 4650.441946966352, 4686.320483529903, 7372.410207183412, 7528.435367347471, 7534.849303013909, 7550.6979720811105, 7552.320291173821, 4684.5549672781035, 4693.530646935279, 4696.436427628706, 4710.52894710533, 4726.527347265314, 4767.213409787346, 4767.976160119178, 4776.068666609977, 4779.0, 4779.187538118528, 5657.0, 5671.092625180034, 5673.914891276634, 5676.631018983753, 5697.145428185722, 6887.139107611549, 7127.572345659277, 7147.178074521393, 7157.284271572905, 7173.282671732889, 1629.6414788746456, 1644.761509581104, 1652.7768751218432, 1653.2395098254938, 1654.487108996215, 985.103555764251, 1034.5758239121924, 1039.2050081687514, 1059.7721489879714, 1060.6923992042375, 5616.0171969905105, 6468.8059358219325, 6765.0, 6792.592444453348, 6803.319668033256, 7488.925110748932, 7489.13874904393, 7499.662515186829, 7499.962500187464, 7512.774616761506, 6731.182580703183, 6736.326367363225, 6738.764143254963, 6747.966260168668, 6758.0, 3889.202713443737, 3943.428202910562, 3945.5204298388103, 3947.4276229946495, 3956.624120708528, 6324.747010119636, 7565.6973721050545, 7820.530768153815, 7834.529928204326, 7838.725644602411, 7627.389808815282, 7695.268949449794, 7713.961430192813, 7813.296803287732, 7819.218078192135, 7904.367650587939, 7910.5253684777945, 7943.126256111823, 7946.483478574106, 7954.721584744506, 6076.301225358963, 7230.385417239593, 7397.4082073435075, 7518.736844210426, 7542.0, 7278.344948954407, 7373.0, 7378.667959941708, 7391.889121663287, 7392.926070739439, 7881.290683838482, 7888.802779930547, 7891.210878912063, 7892.289693927574, 7897.68409263646, 4058.736182148151, 4722.669413141047, 4731.243001119804, 4734.881627959328, 4735.12400205963, 7206.459515536251, 7432.777016689508, 7451.031365922396, 7470.813229669302, 7470.991416158749, 7160.319769621878, 7206.315400036988, 7215.025971493781, 7218.639068046682, 7230.349268565854, 6271.717772700331, 6753.3246675332075, 6805.89791153143, 7175.103112110986, 7181.461390395846, 6170.938290617216, 7042.014118023583, 7044.87282034872, 7046.823829404306, 7077.150741911004, 6909.896351554831, 7185.820354491179, 7188.5327453715345, 7193.856122877409, 7202.747903823341, 6783.219929708147, 6903.206131294965, 6909.170899492093, 7060.54106483077, 7082.610456424946], "value": 5214.040189404188, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.5945590741231, 747.0363231431454, 769.8383339498705, 784.7959530522063, 799.0411506192567, 14113.717725645482, 15139.015963962349, 15610.531684049469, 15941.043537387735, 15953.521394358158, 14381.0, 14503.98472106953, 14519.346629401702, 14558.126512409262, 14574.562763117097, 775.5695588948131, 795.0578564401175, 948.7390967483929, 978.432509144698, 981.175812317653, 965.3966271080575, 974.1038244814752, 976.3117002513246, 977.472165030886, 978.5792109392958, 826.995865020674, 1030.9072183503483, 1032.220673391592, 1033.00315195836, 1035.4512108582464, 993.7913038261973, 1034.9379037257752, 1035.585765693721, 1036.1037702387455, 1037.7353774787457, 765.0245936431095, 765.9846803063963, 767.4819496839667, 768.9923100769032, 771.2942657468466, 15372.692546149014, 15467.92266038674, 15489.0, 15505.844941550553, 15507.689846203126, 13187.934060329733, 13483.595492135251, 13511.864881351257, 13520.932395338059, 13791.172529648253, 10452.895471045269, 10516.0, 10628.787424251548, 10859.0, 10911.236213465061, 3641.107928557494, 3659.8536058557625, 3674.210044840372, 3706.6664000240107, 3860.7490513116836, 84285.0, 85305.57347213286, 85623.0, 85870.14129858684, 86377.56811215967, 10077.899221007696, 10215.489225538842, 10217.029382208679, 10608.363498190134, 10808.405537695502, 23913.282601521983, 23967.921443535084, 24172.0, 24238.030478780653, 24273.029078837, 7844.411669124838, 7862.2137786222065, 7877.566733829694, 7892.802679932933, 7926.524408535391, 4102.118044620421, 4151.481064866892, 4193.245215861113, 4197.97901010493, 4237.936430953539, 3790.1093243087853, 3831.1571454280015, 3844.000559854458, 3848.3265428550453, 3851.2490064437725, 671.993280067193, 688.5352387138762, 692.9826754331182, 694.3230350408412, 703.1772825793834, 527.9683219006872, 586.5630105571377, 607.8632307730725, 610.2311088029027, 660.3429587560408, 8454.1123182065, 8472.49165050099, 8482.406231563855, 8575.485470871768, 8969.730908072768, 589.2134001108483, 597.3280059932575, 612.2897438970773, 641.9646919419476, 643.0226056394255, 3827.827747751302, 3929.862454814068, 3937.448757173998, 4040.6565441937764, 4195.58044195578, 84827.5758621203, 85931.0, 94541.5272923631, 94823.52588237014, 94967.0, 80198.0, 80262.59868700619, 80470.0, 80672.59663701644, 82020.0, 67184.6640766793, 67422.66288668623, 71786.9231961521, 72425.0, 73024.0, 6736.494762892705, 7813.960930195312, 7982.521048737094, 8108.83782324362, 8314.87527687073, 50199.49800501948, 50348.748256258485, 50574.494255056976, 51162.46512604628, 51222.7194320145, 55307.44692553023, 64856.70286594147, 67825.66087169532, 67965.0, 69497.0, 10201.438920859127, 10312.688362732484, 10555.313904596176, 10570.577176912837, 11238.044766194966, 10807.297525660806, 10915.508802103766, 11063.557457701601, 11278.379689117177, 11343.773124537629, 10386.48067596602, 10661.09380702649, 11311.773764524827, 11908.0, 12138.514459421523, 11553.537858485566, 11569.247998880046, 11642.70893227676, 11832.645020649394, 12312.199707019015, 11157.944210278896, 11251.774964500828, 11269.774604508028, 11549.826752598885, 11562.826557601475, 9818.75453113678, 9845.60617575289, 10050.447225402673, 10129.746756331151, 10204.948975255076, 9863.506824658882, 9928.354656947275, 9929.503524823585, 10037.799244014932, 10089.344192627455], "value": 7138.181797816845, "unit": "score"}], "name": "dromaeo_css", "value": 7632.573231810499, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 120.0, 111.0, 111.0, 112.0, 112.0, 112.0, 113.0, 135.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 116.0, 141.0, 161.0, 118.0, 136.0, 119.0, 151.0, 152.0, 118.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 184.0, 186.0, 185.0, 185.0, 183.0, 184.0, 184.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 73.0, 150.0, 57.0, 55.0, 58.0, 61.0, 129.0, 54.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 140.0, 80.0, 89.0, 86.0, 125.0, 79.0, 77.0, 155.0], "value": 101.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 88.0, 86.0, 126.0, 128.0, 117.0, 84.0], "value": 97.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 104.0, 131.0, 87.0, 131.0, 94.0, 93.0, 93.0, 93.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 100.0, 139.0, 141.0, 86.0, 86.0, 85.0, 86.0, 85.0, 86.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 63.0, 64.0, 64.0, 60.0, 62.0, 77.0, 58.0, 56.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 58.0, 74.0, 74.0, 77.0, 68.0, 66.0, 65.0, 66.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 80.0, 79.0, 79.0, 78.0, 78.0, 78.0, 79.0, 82.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 124.0, 129.0, 144.0, 156.0, 143.0, 112.0, 168.0, 165.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 163.0, 171.0, 167.0, 162.0, 162.0, 161.0, 173.0, 161.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 66.0, 67.0, 66.0, 67.0, 65.0, 66.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1492.1999999999998, "alertThreshold": 2.0}]} 08:06:48 INFO - Return code: 0 08:06:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:06:48 ERROR - # TBPL SUCCESS # 08:06:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:06:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:06:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:06:48 INFO - 2016-07-15 08:06:48.757 screenresolution[1689:11200] starting screenresolution argv=screenresolution get 08:06:48 INFO - 2016-07-15 08:06:48.759 screenresolution[1689:11200] Display 0: 1600x1200x32@60 08:06:48 INFO - 2016-07-15 08:06:48.765 screenresolution[1690:11203] starting screenresolution argv=screenresolution list 08:06:48 INFO - Available Modes on Display 0 08:06:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:06:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:06:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:06:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:06:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:06:48 INFO - Intel Iris: 08:06:48 INFO - Chipset Model: Intel Iris 08:06:48 INFO - Type: GPU 08:06:48 INFO - Bus: Built-In 08:06:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:06:48 INFO - Vendor: Intel (0x8086) 08:06:48 INFO - Device ID: 0x0a2e 08:06:48 INFO - Revision ID: 0x0009 08:06:48 INFO - Displays: 08:06:48 INFO - Display: 08:06:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:06:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:06:48 INFO - Display Serial Number: 1600x1200 60 08:06:48 INFO - Main Display: Yes 08:06:48 INFO - Mirror: Off 08:06:48 INFO - Online: Yes 08:06:48 INFO - Rotation: Supported 08:06:49 INFO - Return code: 0 08:06:49 INFO - Running post-action listener: _resource_record_post_action 08:06:49 INFO - Running post-run listener: _resource_record_post_run 08:06:50 INFO - Total resource usage - Wall time: 1195s; CPU: 53.0%; Read bytes: 31727104; Write bytes: 495042048; Read time: 200; Write time: 1527 08:06:50 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 191332864; Write bytes: 185207808; Read time: 17523; Write time: 386 08:06:50 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 29945344; Write bytes: 297962496; Read time: 186; Write time: 1099 08:06:50 INFO - Running post-run listener: _upload_blobber_files 08:06:50 INFO - Blob upload gear active. 08:06:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:06:50 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 08:06:50 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:06:50 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:06:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:06:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:06:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:06:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:51 INFO - (blobuploader) - INFO - Done attempting. 08:06:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:06:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:06:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:52 INFO - (blobuploader) - INFO - Done attempting. 08:06:52 INFO - (blobuploader) - INFO - Iteration through files over. 08:06:52 INFO - Return code: 0 08:06:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:06:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:06:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14c759a1e472b751a3924589b514f79698e7eb781f60a6322ec2d2453dc71ebe5457670193c9b6b4ed1f1347ef37a7330b87098eacf21181294370d32eef7773", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81d42cf2d2e3afb29593b73ff3701b815ffe2ccc5fe1da03decb2f2761f4e52eb6fbfcb968c3b2bc9821f5271b4a2bb1831e078c6b9ce4837151426823fce9db"} 08:06:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:06:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:06:52 INFO - Contents: 08:06:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14c759a1e472b751a3924589b514f79698e7eb781f60a6322ec2d2453dc71ebe5457670193c9b6b4ed1f1347ef37a7330b87098eacf21181294370d32eef7773", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81d42cf2d2e3afb29593b73ff3701b815ffe2ccc5fe1da03decb2f2761f4e52eb6fbfcb968c3b2bc9821f5271b4a2bb1831e078c6b9ce4837151426823fce9db"} 08:06:52 INFO - Running post-run listener: copy_logs_to_upload_dir 08:06:52 INFO - Copying logs to upload dir... 08:06:52 INFO - mkdir: /builds/slave/test/build/upload/logs 08:06:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.795310 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-15 08:06:52.118587) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 08:06:52.121894) ========= 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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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/Mozilla-Beta/sha512/14c759a1e472b751a3924589b514f79698e7eb781f60a6322ec2d2453dc71ebe5457670193c9b6b4ed1f1347ef37a7330b87098eacf21181294370d32eef7773", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81d42cf2d2e3afb29593b73ff3701b815ffe2ccc5fe1da03decb2f2761f4e52eb6fbfcb968c3b2bc9821f5271b4a2bb1831e078c6b9ce4837151426823fce9db"} build_url:https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010921 build_url: 'https://queue.taskcluster.net/v1/task/Hbohv7t2S46VHo5jpoEfHg/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14c759a1e472b751a3924589b514f79698e7eb781f60a6322ec2d2453dc71ebe5457670193c9b6b4ed1f1347ef37a7330b87098eacf21181294370d32eef7773", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81d42cf2d2e3afb29593b73ff3701b815ffe2ccc5fe1da03decb2f2761f4e52eb6fbfcb968c3b2bc9821f5271b4a2bb1831e078c6b9ce4837151426823fce9db"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 08:06:52.154351) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 08:06:52.154683) ========= 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.ZAOuh4hmxk/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.iOBq1pw3WG/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.005026 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 08:06:52.203396) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 08:06:52.203751) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 08:06:52.204102) ========= ========= Total master_lag: 0.22 =========