builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0194 starttime: 1461845699.67 results: success (0) buildid: 20160428030218 builduid: c7baa838da0b448badf6c92ee65235a8 revision: 86730d0a82093d705e44f33a34973d28b269f1ea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 05:14:59.671659) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 05:14:59.672113) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 05:14:59.672404) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 05:14:59.948770) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 05:14:59.949170) ========= 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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-04-28 05:14: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% 9.05M=0.001s 2016-04-28 05:15:00 (9.05 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097300 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 05:15:00.090644) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 05:15:00.090957) ========= 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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.025862 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 05:15:00.185311) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 05:15:00.185617) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 86730d0a82093d705e44f33a34973d28b269f1ea --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 86730d0a82093d705e44f33a34973d28b269f1ea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-04-28 05:15:00,275 truncating revision to first 12 chars 2016-04-28 05:15:00,275 Setting DEBUG logging. 2016-04-28 05:15:00,275 attempt 1/10 2016-04-28 05:15:00,275 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/86730d0a8209?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 05:15:00,905 unpacking tar archive at: mozilla-central-86730d0a8209/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980046 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 05:15:01.205966) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 05:15:01.206319) ========= script_repo_revision: 86730d0a82093d705e44f33a34973d28b269f1ea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 05:15:01.206760) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 05:15:01.207034) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 05:15:01.309549) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 51 secs) (at 2016-04-28 05:15:01.310000) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:15:01 INFO - MultiFileLogger online at 20160428 05:15:01 in /builds/slave/test 05:15:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:15:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:15:01 INFO - {'append_to_log': False, 05:15:01 INFO - 'base_work_dir': '/builds/slave/test', 05:15:01 INFO - 'blob_upload_branch': 'Firefox', 05:15:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:15:01 INFO - 'branch': 'Firefox', 05:15:01 INFO - 'buildbot_json_path': 'buildprops.json', 05:15:01 INFO - 'config_files': ('talos/mac_config.py',), 05:15:01 INFO - 'default_actions': ('clobber', 05:15:01 INFO - 'read-buildbot-config', 05:15:01 INFO - 'download-and-extract', 05:15:01 INFO - 'populate-webroot', 05:15:01 INFO - 'create-virtualenv', 05:15:01 INFO - 'install', 05:15:01 INFO - 'run-tests'), 05:15:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:15:01 INFO - 'download_minidump_stackwalk': True, 05:15:01 INFO - 'download_symbols': 'ondemand', 05:15:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:15:01 INFO - 'tooltool.py': '/tools/tooltool.py', 05:15:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:15:01 INFO - '/tools/misc-python/virtualenv.py')}, 05:15:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:15:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:15:01 INFO - 'installer_path': 'installer.exe', 05:15:01 INFO - 'log_level': 'info', 05:15:01 INFO - 'log_name': 'talos', 05:15:01 INFO - 'log_to_console': True, 05:15:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:15:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:15:01 INFO - 'opt_config_files': (), 05:15:01 INFO - 'pip_index': False, 05:15:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:01 INFO - 'cmd': ('bash', 05:15:01 INFO - '-c', 05:15:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:01 INFO - 'enabled': True, 05:15:01 INFO - 'halt_on_failure': False, 05:15:01 INFO - 'name': 'check_screen_resolution'},), 05:15:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:01 INFO - 'cmd': ('bash', 05:15:01 INFO - '-c', 05:15:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:01 INFO - 'enabled': True, 05:15:01 INFO - 'halt_on_failure': False, 05:15:01 INFO - 'name': 'check_screen_resolution'},), 05:15:01 INFO - 'run_cmd_checks_enabled': True, 05:15:01 INFO - 'sps_profile': False, 05:15:01 INFO - 'sps_profile_interval': 0, 05:15:01 INFO - 'suite': 'dromaeojs-e10s', 05:15:01 INFO - 'system_bits': '32', 05:15:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:15:01 INFO - 'title': 't-yosemite-r7-0194', 05:15:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:15:01 INFO - 'use_talos_json': True, 05:15:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:15:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:15:01 INFO - 'work_dir': 'build'} 05:15:01 INFO - ##### 05:15:01 INFO - ##### Running clobber step. 05:15:01 INFO - ##### 05:15:01 INFO - Running pre-action listener: _resource_record_pre_action 05:15:01 INFO - Running main action method: clobber 05:15:01 INFO - rmtree: /builds/slave/test/build 05:15:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:15:03 INFO - Running post-action listener: _resource_record_post_action 05:15:03 INFO - ##### 05:15:03 INFO - ##### Running read-buildbot-config step. 05:15:03 INFO - ##### 05:15:03 INFO - Running pre-action listener: _resource_record_pre_action 05:15:03 INFO - Running main action method: read_buildbot_config 05:15:03 INFO - Using buildbot properties: 05:15:03 INFO - { 05:15:03 INFO - "project": "", 05:15:03 INFO - "product": "firefox", 05:15:03 INFO - "script_repo_revision": "production", 05:15:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:15:03 INFO - "repository": "", 05:15:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:15:03 INFO - "buildid": "20160428030218", 05:15:03 INFO - "stage_platform": "macosx64", 05:15:03 INFO - "basedir": "/builds/slave/test", 05:15:03 INFO - "nightly_build": "True", 05:15:03 INFO - "buildnumber": 258, 05:15:03 INFO - "slavename": "t-yosemite-r7-0194", 05:15:03 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:15:03 INFO - "platform": "yosemite_r7", 05:15:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:15:03 INFO - "branch": "mozilla-central", 05:15:03 INFO - "repo_path": "mozilla-central", 05:15:03 INFO - "revision": "86730d0a82093d705e44f33a34973d28b269f1ea", 05:15:03 INFO - "pgo_build": "False", 05:15:03 INFO - "builduid": "c7baa838da0b448badf6c92ee65235a8", 05:15:03 INFO - "slavebuilddir": "test" 05:15:03 INFO - } 05:15:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:15:03 INFO - Running post-action listener: _resource_record_post_action 05:15:03 INFO - ##### 05:15:03 INFO - ##### Running download-and-extract step. 05:15:03 INFO - ##### 05:15:03 INFO - Running pre-action listener: _resource_record_pre_action 05:15:03 INFO - Running main action method: download_and_extract 05:15:03 INFO - mkdir: /builds/slave/test/build/tests 05:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:03 INFO - https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:15:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:15:05 INFO - Downloaded 1199 bytes. 05:15:05 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:05 INFO - Using the following test package requirements: 05:15:05 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:15:05 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:05 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:15:05 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:05 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:15:05 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:05 INFO - u'jsshell-mac.zip'], 05:15:05 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:05 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:15:05 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:15:05 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:05 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:15:05 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:05 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:15:05 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:05 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:15:05 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:05 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:15:05 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:15:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:05 INFO - https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:15:09 INFO - Downloaded 18116213 bytes. 05:15:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:15:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:15:12 INFO - Return code: 0 05:15:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:12 INFO - https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:15:50 INFO - Downloaded 18116213 bytes. 05:15:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:15:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:15:54 INFO - Return code: 0 05:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:54 INFO - https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:15:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:15:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:15:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:15:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:15:57 INFO - Downloaded 10840145 bytes. 05:15:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:15:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:15:58 INFO - Return code: 0 05:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:58 INFO - https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:15:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:15:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:15:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:17:02 INFO - Downloaded 110342470 bytes. 05:17:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:02 INFO - mkdir: /builds/slave/test/properties 05:17:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:02 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:02 INFO - Contents: 05:17:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:04 INFO - Running post-action listener: _resource_record_post_action 05:17:04 INFO - Running post-action listener: set_extra_try_arguments 05:17:04 INFO - ##### 05:17:04 INFO - ##### Running populate-webroot step. 05:17:04 INFO - ##### 05:17:04 INFO - Running pre-action listener: _resource_record_pre_action 05:17:04 INFO - Running main action method: populate_webroot 05:17:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:04 INFO - '--tppagecycles', 05:17:04 INFO - '7'], 05:17:04 INFO - 'tests': ['tsvgm']}}, 05:17:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:17:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:17:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:04 INFO - 'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:04 INFO - 'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['damp', 'tps']}, 05:17:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:04 INFO - 'tests': ['damp', 'tps']}, 05:17:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['dromaeo_dom']}, 05:17:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['a11yr', 05:17:04 INFO - 'ts_paint', 05:17:04 INFO - 'tpaint', 05:17:04 INFO - 'sessionrestore', 05:17:04 INFO - 'sessionrestore_no_auto_restore', 05:17:04 INFO - 'tabpaint']}, 05:17:04 INFO - 'other-e10s': {'tests': ['a11yr', 05:17:04 INFO - 'ts_paint', 05:17:04 INFO - 'tpaint', 05:17:04 INFO - 'sessionrestore', 05:17:04 INFO - 'sessionrestore_no_auto_restore', 05:17:04 INFO - 'tabpaint']}, 05:17:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:17:04 INFO - 'ts_paint', 05:17:04 INFO - 'tpaint', 05:17:04 INFO - 'sessionrestore', 05:17:04 INFO - 'sessionrestore_no_auto_restore', 05:17:04 INFO - 'tabpaint']}, 05:17:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:17:04 INFO - 'ts_paint', 05:17:04 INFO - 'tpaint', 05:17:04 INFO - 'sessionrestore', 05:17:04 INFO - 'sessionrestore_no_auto_restore', 05:17:04 INFO - 'tabpaint']}, 05:17:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['a11yr', 05:17:04 INFO - 'ts_paint', 05:17:04 INFO - 'tpaint', 05:17:04 INFO - 'sessionrestore', 05:17:04 INFO - 'sessionrestore_no_auto_restore', 05:17:04 INFO - 'tabpaint']}, 05:17:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['a11yr', 05:17:04 INFO - 'ts_paint', 05:17:04 INFO - 'tpaint', 05:17:04 INFO - 'sessionrestore', 05:17:04 INFO - 'sessionrestore_no_auto_restore', 05:17:04 INFO - 'tabpaint']}, 05:17:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['tsvgx', 05:17:04 INFO - 'tsvgr_opacity', 05:17:04 INFO - 'tart', 05:17:04 INFO - 'tscrollx', 05:17:04 INFO - 'cart']}, 05:17:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:17:04 INFO - 'tsvgr_opacity', 05:17:04 INFO - 'tart', 05:17:04 INFO - 'tscrollx', 05:17:04 INFO - 'cart']}, 05:17:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:04 INFO - 'talos_options': ['--disable-e10s'], 05:17:04 INFO - 'tests': ['tp5o']}, 05:17:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:04 INFO - 'tests': ['tp5o']}, 05:17:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:04 INFO - 'talos_options': ['--disable-e10s', 05:17:04 INFO - '--xperf_path', 05:17:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:04 INFO - 'tests': ['tp5n']}, 05:17:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:04 INFO - 'talos_options': ['--xperf_path', 05:17:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:04 INFO - 'tests': ['tp5n']}}, 05:17:04 INFO - 'talos.zip': {'path': '', 05:17:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:04 INFO - Running post-action listener: _resource_record_post_action 05:17:04 INFO - ##### 05:17:04 INFO - ##### Running create-virtualenv step. 05:17:04 INFO - ##### 05:17:04 INFO - Running pre-action listener: _resource_record_pre_action 05:17:04 INFO - Running main action method: create_virtualenv 05:17:04 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:17:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:04 INFO - Using real prefix '/tools/python27' 05:17:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:05 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:07 INFO - Installing pip.................done. 05:17:07 INFO - Return code: 0 05:17:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:08 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 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:08 INFO - 'HOME': '/Users/cltbld', 05:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:08 INFO - 'LOGNAME': 'cltbld', 05:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:08 INFO - 'MOZ_NO_REMOTE': '1', 05:17:08 INFO - 'NO_EM_RESTART': '1', 05:17:08 INFO - 'PAGER': '/bin/cat', 05:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:08 INFO - 'PWD': '/builds/slave/test', 05:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:08 INFO - 'SHELL': '/bin/bash', 05:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:08 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:08 INFO - 'USER': 'cltbld', 05:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:08 INFO - 'XPC_FLAGS': '0x0', 05:17:08 INFO - 'XPC_SERVICE_NAME': '0', 05:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:17:08 INFO - Cleaning up... 05:17:08 INFO - Return code: 0 05:17:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:08 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 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:08 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 05:17:08 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 05:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:08 INFO - 'HOME': '/Users/cltbld', 05:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:08 INFO - 'LOGNAME': 'cltbld', 05:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:08 INFO - 'MOZ_NO_REMOTE': '1', 05:17:08 INFO - 'NO_EM_RESTART': '1', 05:17:08 INFO - 'PAGER': '/bin/cat', 05:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:08 INFO - 'PWD': '/builds/slave/test', 05:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:08 INFO - 'SHELL': '/bin/bash', 05:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:08 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:08 INFO - 'USER': 'cltbld', 05:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:08 INFO - 'XPC_FLAGS': '0x0', 05:17:08 INFO - 'XPC_SERVICE_NAME': '0', 05:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:08 INFO - Downloading/unpacking psutil>=0.7.1 05:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:11 INFO - Installing collected packages: psutil 05:17:11 INFO - Running setup.py install for psutil 05:17:12 INFO - building 'psutil._psutil_osx' extension 05:17:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:17:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:17:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:17:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:17:12 INFO - building 'psutil._psutil_posix' extension 05:17:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:17:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:17:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:17:12 INFO - ^ 05:17:13 INFO - 1 warning generated. 05:17:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:17:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:13 INFO - Successfully installed psutil 05:17:13 INFO - Cleaning up... 05:17:13 INFO - Return code: 0 05:17:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:13 INFO - 'HOME': '/Users/cltbld', 05:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:13 INFO - 'LOGNAME': 'cltbld', 05:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:13 INFO - 'MOZ_NO_REMOTE': '1', 05:17:13 INFO - 'NO_EM_RESTART': '1', 05:17:13 INFO - 'PAGER': '/bin/cat', 05:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:13 INFO - 'PWD': '/builds/slave/test', 05:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:13 INFO - 'SHELL': '/bin/bash', 05:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:13 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:13 INFO - 'USER': 'cltbld', 05:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:13 INFO - 'XPC_FLAGS': '0x0', 05:17:13 INFO - 'XPC_SERVICE_NAME': '0', 05:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:17:15 INFO - Installing collected packages: mozsystemmonitor 05:17:15 INFO - Running setup.py install for mozsystemmonitor 05:17:16 INFO - Successfully installed mozsystemmonitor 05:17:16 INFO - Cleaning up... 05:17:16 INFO - Return code: 0 05:17:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:16 INFO - 'HOME': '/Users/cltbld', 05:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:16 INFO - 'LOGNAME': 'cltbld', 05:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:16 INFO - 'MOZ_NO_REMOTE': '1', 05:17:16 INFO - 'NO_EM_RESTART': '1', 05:17:16 INFO - 'PAGER': '/bin/cat', 05:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:16 INFO - 'PWD': '/builds/slave/test', 05:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:16 INFO - 'SHELL': '/bin/bash', 05:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:16 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:16 INFO - 'USER': 'cltbld', 05:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:16 INFO - 'XPC_FLAGS': '0x0', 05:17:16 INFO - 'XPC_SERVICE_NAME': '0', 05:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:16 INFO - Downloading/unpacking blobuploader==1.2.4 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:18 INFO - Downloading blobuploader-1.2.4.tar.gz 05:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:17:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:17:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:19 INFO - Downloading docopt-0.6.1.tar.gz 05:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:17:20 INFO - Installing collected packages: blobuploader, requests, docopt 05:17:20 INFO - Running setup.py install for blobuploader 05:17:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:17:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:17:20 INFO - Running setup.py install for requests 05:17:20 INFO - Running setup.py install for docopt 05:17:20 INFO - Successfully installed blobuploader requests docopt 05:17:20 INFO - Cleaning up... 05:17:21 INFO - Return code: 0 05:17:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:21 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 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:17:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:21 INFO - 'HOME': '/Users/cltbld', 05:17:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:21 INFO - 'LOGNAME': 'cltbld', 05:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:21 INFO - 'MOZ_NO_REMOTE': '1', 05:17:21 INFO - 'NO_EM_RESTART': '1', 05:17:21 INFO - 'PAGER': '/bin/cat', 05:17:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:21 INFO - 'PWD': '/builds/slave/test', 05:17:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:21 INFO - 'SHELL': '/bin/bash', 05:17:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:21 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:21 INFO - 'USER': 'cltbld', 05:17:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:21 INFO - 'XPC_FLAGS': '0x0', 05:17:21 INFO - 'XPC_SERVICE_NAME': '0', 05:17:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:21 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-F_odKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:21 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Xe99gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:21 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-W3up1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:21 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-eKcf4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:21 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-rW9tDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:21 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-GbbKmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:21 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Y3wuFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ZwRH6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-wuu_ay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-n8ZLIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-pUCc5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-87B_pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-0I_cTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-j83gAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-HJwRl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-YvGW1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:22 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-eXqEnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:17:23 INFO - Running setup.py install for manifestparser 05:17:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:17:23 INFO - Running setup.py install for mozcrash 05:17:23 INFO - Running setup.py install for mozdebug 05:17:23 INFO - Running setup.py install for mozdevice 05:17:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:17:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:17:23 INFO - Running setup.py install for mozfile 05:17:23 INFO - Running setup.py install for mozhttpd 05:17:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:17:23 INFO - Running setup.py install for mozinfo 05:17:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Running setup.py install for mozInstall 05:17:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Running setup.py install for mozleak 05:17:24 INFO - Running setup.py install for mozlog 05:17:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Running setup.py install for moznetwork 05:17:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Running setup.py install for mozprocess 05:17:24 INFO - Running setup.py install for mozprofile 05:17:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:17:24 INFO - Running setup.py install for mozrunner 05:17:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:17:25 INFO - Running setup.py install for mozscreenshot 05:17:25 INFO - Running setup.py install for moztest 05:17:25 INFO - Running setup.py install for mozversion 05:17:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:17:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:17:25 INFO - Cleaning up... 05:17:25 INFO - Return code: 0 05:17:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:25 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 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:25 INFO - 'HOME': '/Users/cltbld', 05:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:25 INFO - 'LOGNAME': 'cltbld', 05:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:25 INFO - 'MOZ_NO_REMOTE': '1', 05:17:25 INFO - 'NO_EM_RESTART': '1', 05:17:25 INFO - 'PAGER': '/bin/cat', 05:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:25 INFO - 'PWD': '/builds/slave/test', 05:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:25 INFO - 'SHELL': '/bin/bash', 05:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:25 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:25 INFO - 'USER': 'cltbld', 05:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:25 INFO - 'XPC_FLAGS': '0x0', 05:17:25 INFO - 'XPC_SERVICE_NAME': '0', 05:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:25 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-x6Vxq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:25 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-n4iUh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-M0vmiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-IMxhtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-tfhLgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-cMjXuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-bzTrv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-LVMwkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Xkdj6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-CesTW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:26 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)) 05:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:26 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-yzC2Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:27 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-H50zLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:27 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-k9sJzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:27 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ZxyM0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:27 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-5YTwNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:27 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-_Bv0HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:27 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jwcSfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:17:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:17: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 05:17: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 05:17: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 05:17: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 05:17: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 05:17: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 05:17:30 INFO - Downloading blessings-1.6.tar.gz 05:17:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:17:30 INFO - Installing collected packages: blessings 05:17:30 INFO - Running setup.py install for blessings 05:17:30 INFO - Successfully installed blessings 05:17:30 INFO - Cleaning up... 05:17:30 INFO - Return code: 0 05:17:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:30 INFO - Reading from file tmpfile_stdout 05:17:30 INFO - Current package versions: 05:17:30 INFO - blessings == 1.6 05:17:30 INFO - blobuploader == 1.2.4 05:17:30 INFO - docopt == 0.6.1 05:17:30 INFO - manifestparser == 1.1 05:17:30 INFO - mozInstall == 1.12 05:17:30 INFO - mozcrash == 0.17 05:17:30 INFO - mozdebug == 0.1 05:17:30 INFO - mozdevice == 0.48 05:17:30 INFO - mozfile == 1.2 05:17:30 INFO - mozhttpd == 0.7 05:17:30 INFO - mozinfo == 0.9 05:17:30 INFO - mozleak == 0.1 05:17:30 INFO - mozlog == 3.1 05:17:30 INFO - moznetwork == 0.27 05:17:30 INFO - mozprocess == 0.22 05:17:30 INFO - mozprofile == 0.28 05:17:30 INFO - mozrunner == 6.11 05:17:30 INFO - mozscreenshot == 0.1 05:17:30 INFO - mozsystemmonitor == 0.0 05:17:30 INFO - moztest == 0.7 05:17:30 INFO - mozversion == 1.4 05:17:30 INFO - psutil == 3.1.1 05:17:30 INFO - requests == 1.2.3 05:17:30 INFO - wsgiref == 0.1.2 05:17:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:30 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 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:30 INFO - 'HOME': '/Users/cltbld', 05:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:30 INFO - 'LOGNAME': 'cltbld', 05:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:30 INFO - 'MOZ_NO_REMOTE': '1', 05:17:30 INFO - 'NO_EM_RESTART': '1', 05:17:30 INFO - 'PAGER': '/bin/cat', 05:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:30 INFO - 'PWD': '/builds/slave/test', 05:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:30 INFO - 'SHELL': '/bin/bash', 05:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:30 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:30 INFO - 'USER': 'cltbld', 05:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:30 INFO - 'XPC_FLAGS': '0x0', 05:17:30 INFO - 'XPC_SERVICE_NAME': '0', 05:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:17:30 INFO - Cleaning up... 05:17:30 INFO - Return code: 0 05:17:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17: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', 'jsonschema'] in /builds/slave/test/build 05:17: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 jsonschema 05:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:30 INFO - 'HOME': '/Users/cltbld', 05:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:30 INFO - 'LOGNAME': 'cltbld', 05:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:30 INFO - 'MOZ_NO_REMOTE': '1', 05:17:30 INFO - 'NO_EM_RESTART': '1', 05:17:30 INFO - 'PAGER': '/bin/cat', 05:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:30 INFO - 'PWD': '/builds/slave/test', 05:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:30 INFO - 'SHELL': '/bin/bash', 05:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:30 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:30 INFO - 'USER': 'cltbld', 05:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:30 INFO - 'XPC_FLAGS': '0x0', 05:17:30 INFO - 'XPC_SERVICE_NAME': '0', 05:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:31 INFO - Downloading/unpacking jsonschema 05:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:17:33 INFO - Downloading/unpacking functools32 (from jsonschema) 05:17: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 05:17: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 05:17: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 05:17: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 05:17: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 05:17: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 05:17:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:17:34 INFO - warning: no files found matching '*.txt' 05:17:34 INFO - no previously-included directories found matching 'build' 05:17:34 INFO - no previously-included directories found matching 'dist' 05:17:34 INFO - no previously-included directories found matching '.git*' 05:17:34 INFO - Installing collected packages: jsonschema, functools32 05:17:34 INFO - Running setup.py install for functools32 05:17:34 INFO - warning: no files found matching '*.txt' 05:17:34 INFO - no previously-included directories found matching 'build' 05:17:34 INFO - no previously-included directories found matching 'dist' 05:17:34 INFO - no previously-included directories found matching '.git*' 05:17:34 INFO - Successfully installed jsonschema functools32 05:17:34 INFO - Cleaning up... 05:17:34 INFO - Return code: 0 05:17:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a95420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9015963f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, '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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:34 INFO - 'HOME': '/Users/cltbld', 05:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:34 INFO - 'LOGNAME': 'cltbld', 05:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:34 INFO - 'MOZ_NO_REMOTE': '1', 05:17:34 INFO - 'NO_EM_RESTART': '1', 05:17:34 INFO - 'PAGER': '/bin/cat', 05:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:34 INFO - 'PWD': '/builds/slave/test', 05:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:34 INFO - 'SHELL': '/bin/bash', 05:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:17:34 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:34 INFO - 'USER': 'cltbld', 05:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:34 INFO - 'XPC_FLAGS': '0x0', 05:17:34 INFO - 'XPC_SERVICE_NAME': '0', 05:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:34 INFO - Downloading/unpacking flake8 05:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:17:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:17:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:17:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:17:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:17:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:17:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:17:38 INFO - no previously-included directories found matching 'docs/_build' 05:17:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:17:38 INFO - Running setup.py install for pep8 05:17:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:17:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:17:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:17:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:17:38 INFO - no previously-included directories found matching 'docs/_build' 05:17:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:17:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:17:38 INFO - Cleaning up... 05:17:38 INFO - Return code: 0 05:17:38 INFO - Running post-action listener: _resource_record_post_action 05:17:38 INFO - Running post-action listener: _start_resource_monitoring 05:17:38 INFO - Starting resource monitoring. 05:17:38 INFO - ##### 05:17:38 INFO - ##### Running install step. 05:17:38 INFO - ##### 05:17:38 INFO - Running pre-action listener: _resource_record_pre_action 05:17:38 INFO - Running main action method: install 05:17:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:39 INFO - Reading from file tmpfile_stdout 05:17:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:17:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:17:39 INFO - Reading from file tmpfile_stdout 05:17:39 INFO - Output received: 05:17:39 INFO - Usage: mozinstall [options] installer 05:17:39 INFO - Options: 05:17:39 INFO - -h, --help show this help message and exit 05:17:39 INFO - -d DEST, --destination=DEST 05:17:39 INFO - Directory to install application into. [default: 05:17:39 INFO - "/builds/slave/test"] 05:17:39 INFO - --app=APP Application being installed. [default: firefox] 05:17:39 INFO - mkdir: /builds/slave/test/build/application 05:17:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:18:13 INFO - Reading from file tmpfile_stdout 05:18:13 INFO - Output received: 05:18:13 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:18:13 INFO - Running post-action listener: _resource_record_post_action 05:18:13 INFO - ##### 05:18:13 INFO - ##### Running run-tests step. 05:18:13 INFO - ##### 05:18:13 INFO - Running pre-action listener: _resource_record_pre_action 05:18:13 INFO - Running main action method: run_tests 05:18:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:18:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:18:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:18:13 INFO - 2016-04-28 05:18:13.113 screenresolution[1656:7756] starting screenresolution argv=screenresolution get 05:18:13 INFO - 2016-04-28 05:18:13.116 screenresolution[1656:7756] Display 0: 1600x1200x32@60 05:18:13 INFO - 2016-04-28 05:18:13.123 screenresolution[1657:7766] starting screenresolution argv=screenresolution list 05:18:13 INFO - Available Modes on Display 0 05:18:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:18:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:18:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:18:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:18:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:18:13 INFO - Intel Iris: 05:18:13 INFO - Chipset Model: Intel Iris 05:18:13 INFO - Type: GPU 05:18:13 INFO - Bus: Built-In 05:18:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:18:13 INFO - Vendor: Intel (0x8086) 05:18:13 INFO - Device ID: 0x0a2e 05:18:13 INFO - Revision ID: 0x0009 05:18:13 INFO - Displays: 05:18:13 INFO - Display: 05:18:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:18:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:18:13 INFO - Display Serial Number: 1600x1200 60 05:18:13 INFO - Main Display: Yes 05:18:13 INFO - Mirror: Off 05:18:13 INFO - Online: Yes 05:18:13 INFO - Rotation: Supported 05:18:13 INFO - Return code: 0 05:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:18:13 INFO - Python 2.7.3 05:18:13 INFO - Return code: 0 05:18:13 INFO - grabbing minidump binary from tooltool 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - 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 0x105924e00>, '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 0x10578ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10591fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:18:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:18:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:18:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:18:13 INFO - Return code: 0 05:18:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:18:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:18:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:18:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:18:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:18:14 INFO - Return code: 0 05:18:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0194 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:18:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KeW5BUsGD/Render', 05:18:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:14 INFO - 'HOME': '/Users/cltbld', 05:18:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:14 INFO - 'LOGNAME': 'cltbld', 05:18:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:18:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:18:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:14 INFO - 'MOZ_NO_REMOTE': '1', 05:18:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:18:14 INFO - 'NO_EM_RESTART': '1', 05:18:14 INFO - 'PAGER': '/bin/cat', 05:18:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:14 INFO - 'PWD': '/builds/slave/test', 05:18:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:18:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:14 INFO - 'SHELL': '/bin/bash', 05:18:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jN5nBdT3Gm/Listeners', 05:18:14 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:18:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:14 INFO - 'USER': 'cltbld', 05:18:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:14 INFO - 'XPC_FLAGS': '0x0', 05:18:14 INFO - 'XPC_SERVICE_NAME': '0', 05:18:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:18:14 INFO - mozversion application_buildid: 20160428030218 05:18:14 INFO - mozversion application_changeset: 86730d0a82093d705e44f33a34973d28b269f1ea 05:18:14 INFO - mozversion application_display_name: Nightly 05:18:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:18:14 INFO - mozversion application_name: Firefox 05:18:14 INFO - mozversion application_remotingname: firefox 05:18:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:18:14 INFO - mozversion application_vendor: Mozilla 05:18:14 INFO - mozversion application_version: 49.0a1 05:18:14 INFO - mozversion platform_buildid: 20160428030218 05:18:14 INFO - mozversion platform_changeset: 86730d0a82093d705e44f33a34973d28b269f1ea 05:18:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:18:14 INFO - mozversion platform_version: 49.0a1 05:18:14 INFO - using testdate: 1461845894 05:18:14 INFO - actual date: 1461845894 05:18:14 INFO - starting webserver on 'localhost:49270' 05:18:14 INFO - SUITE-START | Running 2 tests 05:18:14 INFO - TEST-START | dromaeo_css 05:18:14 INFO - operating with platform_type : mac_ 05:18:14 INFO - Initialising browser for dromaeo_css test... 05:18:14 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpiKxPcL/profile http://localhost:49270/getInfo.html) 05:18:17 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 05:18:17 INFO - PROCESS | 1675 | colorDepth:24 05:18:17 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 05:18:17 INFO - PROCESS | 1675 | __metrics 05:18:18 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:18:18 INFO - TEST-INFO | 1675: exit 0 05:18:18 INFO - Browser initialized. 05:18:18 INFO - Running cycle 1/1 for dromaeo_css test... 05:18:18 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpiKxPcL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:18:30 INFO - PROCESS | 1679 | RSS: Main: 249782272 05:18:30 INFO - PROCESS | 1679 | 05:19:20 INFO - PROCESS | 1679 | 1461845960266 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:21:31 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:21:32 INFO - PROCESS | 1679 | RSS: Main: 262201344 05:21:32 INFO - PROCESS | 1679 | 05:24:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:24:35 INFO - PROCESS | 1679 | RSS: Main: 273747968 05:24:35 INFO - PROCESS | 1679 | 05:27:36 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:27:37 INFO - PROCESS | 1679 | RSS: Main: 275722240 05:27:37 INFO - PROCESS | 1679 | 05:30:39 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:30:39 INFO - PROCESS | 1679 | RSS: Main: 277282816 05:30:39 INFO - PROCESS | 1679 | 05:33:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:33:43 INFO - PROCESS | 1679 | RSS: Main: 280100864 05:33:43 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:36:46 INFO - PROCESS | 1679 | RSS: Main: 281341952 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | __start_tp_report 05:36:46 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 05:36:46 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 05:36:46 INFO - PROCESS | 1679 | |i|pagename|runs| 05:36:46 INFO - PROCESS | 1679 | |0;dojo.html;3259.103746469719;3440.913977150571;3474.4961980512826;3531.2937412517504;3546.237558924831;5501.669899806009;5666.971665141678;5727.055035919078;5730.341010783751;5740.454656807607;5604.663720176793;5640.04119299719;5728.942710572893;5756.539476841864;5884.235049443566;5066.645334826564;5103.234514822788;5145.434002259767;5209.166533354664;5249.973750131263;687.1866292489663;5082.390113186404;5152.562032227265;5166.121759300909;5242.501962313574;3735.8692445764405;5156.407013193493;5191.091558977164;5191.299174611417;5225.4513276105845;5183.3261675981885;5193.506616871391;5203.973980130113;5205.869853253661;5236.345456817898;5069.417017043051;5074.416442109168;5162.535371816554;5172.482751724835;5188.584913206934;5517.641353312062;5624.803131890405;5681.835223779157;5689.431056894319;5695.629784064023;5981.072933695241;6111.327753947062;6177.2587289525545;6212.689365531751;6255.999040153599;6118.923762351576;6239.937600623981;6251.187345645082;6252.968735156341;6290.716917738667;5782.190493330937;5806.187133801272;5807.941920580783;5812.534997200214;5878.118282257653;6203.410675985774;6224.221972253468;6298.968505157445;6313.905291420632;6324.747010119544;5099.3370861787735;6124.295705993779;6128.319797384577;6129.136520723041;6161;6158.753649854045;6242.406971337716;6292.748290068437;6294.937050629526;6304.117423560705;6103.85289575482;6152.661603611755;6185.134081228542;6235.719392627343;6255.843603909848;6223.502119830493;6235.937640623626;6253.937460625336;6256.499480041586;6267.2165979252595;4394.868153955386;4878.317035615017;4940.011997600423;4941.011797640486;4969.503049695001;4287.520805322159;4379;4396.846110386121;4470.240059189946;4524.117797029613;5725.2843394575675;5734.283214598176;5772.624779389326;5778.7688492460675;5783.681897495678;5126.718030508283;5988.28140623128;6250.124982502453;6251.6561589113035;6291.370862913672;5810.82567522975;5812.157237200582;5823.155642431823;5838.824835254949;5880.794172203952;5296.09966305729;5365.248865158881;5424.566034717213;5427.348718153847;5446;6247.312795592481;6263.592866463757;6305.716242769086;6313.463355614732;6325.05124231369;6205.162303089206;6267.122602835516;6268.811935641938;6296.401841825019;6305.054241863757;6221.5333849962335;6257.812265632038;6259.937400625936;6283.717232724447;6316.652584107918;5072.82245121419;5273.34083239595;6089.847753806013;6137.711080673109;6165.722542485689;6131.601445906002;6180.320164781961;6254.718537665907;6258.030005348996;6270.341614130633;5964.433378829004;6093.847653808691;6095.847603809941;6112.625067133501;6118.357572455006;6035.39646035393;6108.949416342483;6141.631502109888;6145.477634401126;6165.475934545614;5945.851353716192;5980.461758441761;5982.790602328898;6004.609700369462;6020.548458865515;6015.939840601538;6109.572329936862;6117.9187529241;6154.107654390178;6166.1983942087245;6028.306744724413;6056.394360563909;6060.666663333559;6066.817995460143;6082.60463069899 05:36:46 INFO - PROCESS | 1679 | |1;ext.html;506.84794561631503;517.6842126302955;520.2508387921393;533.1070456984551;556.4491153757781;36772.896813095584;38577.42133867991;38592;38594;38595.421068683994;39096.413553796716;39866.80066599663;39887.20225595494;39958.60041399585;40088.79955600218;9327.113924177193;9426.717198484022;9445.102715242076;9449;9594.808103837919;9483.81032379352;9530.094641009127;9706;9715.562799673997;9724.270679699015;9674.758131046745;9710.465924374157;9720.61117555297;9759.463229522373;9806.754831129243;9637.277204209677;9708.029197080235;9709.320347575673;9711.61153553857;9744.269179811543;9524.714258572254;9572.5692343844;9607.711768646952;9609.615615375376;9613.279004074722;38110;38187.42718859219;38394.616053839665;38477.42283865744;38516.42225366622;2743.7530622243894;2924.780641451878;3018.1096576509763;3189.4737368334195;3434.1414646338417;23647;26814.46371072587;26847.194584162506;27372;27994.3001424964;2749.848758318292;2759.9448011039867;2768.5431903735857;2883.2647674842783;2970.5841182234503;248018;295570.5664415035;313723.86276137177;314181;314639;32774.67225327764;58377;61873.381266187665;61896;62072.68963655153;34684.47973280403;34713.479297810554;34767.9569612912;34878.65121348805;34945.30109397849;14943.252837358088;15089;15165.317560709795;15183.544493665207;15211.391544338103;12560.685982850502;12823.93588032054;12842.807357889638;12877.742445151045;12883.033772467103;8005.079415867133;8061.838763224702;8134.349252059821;8187.38594605407;8209.589520524069;3036.5293379525765;3325.534425180477;3363.830329437499;3390.3219356128875;3449.7757645752945;931.8276118918022;961.4663861556903;1028.095276156977;1071.0681706914877;1133.7845809296373;25492.47045177258;25558;27500.724992750216;27529.44941101167;27848.582271265946;1065.674969134406;1071.6784964510616;1089.6839916424149;1119.518606998982;1180.7048237940514;14280.714385712228;14463.421463141356;14574.927125364517;14650;15009.549713508612;500452;502418;504981;512015;512735;57175.42824571784;58415.70792146012;58683.70658146682;59122;59148.40851591515;1261.4575732435142;1474.2481334519473;1485.9405623774928;1503.503843731566;1509.2378348933937;27536.586951195346;28241;28377.574336385384;28674.283142920758;28792.280192995342;46628;48197;48877.511224888716;49520.75239623779;49633.255501168234;449764;450964;451704;453187;453966;25994.350141245865;26036.8698156508;26056.34859128537;26068.34829129287;26454.603180952687;44281.557184427744;44733.329000064376;44856.32715509335;45314.54685453235;45415.77292113518;33512.15683137353;36977.815110924275;37022.44466332953;37332.62667373291;38077.238455231294;33897.813576524706;36561.81719091388;36564;37158.88523344304;37523.812380939016;36210.913672589864;36473.63526364809;36480.90557283286;36981.075473113386;37035;31671.683283166873;31831.204219894687;32236;32263.193420164684;32380.35239295248;19763.40709778709;21110.47223819417;22024.0682182625;24144.396390090387;24559.754402455746 05:36:46 INFO - PROCESS | 1679 | |2;jquery.html;390.6425620557188;396.6271704597677;396.8273800896612;397.7752569798064;407.63720288942847;14031.78952315716;19033.904830475847;19580.608387832235;19763.430754001995;19782;14733.410663573444;16469.68242540597;18237.81454205476;19453.416397508063;19529.902350488228;5594.944050559503;5719.771209151629;5868.207791948096;5904.2324497815225;5925.051991681332;5822.243108395903;5839.0949402842625;5866.237389139427;5881.500072493822;5888.440598143169;4785.401824771903;5796.942030579704;5801.7099145042575;5802.448767367115;5802.564807639444;4927.975360123194;5746.195532625415;5778.162166485878;5801.680907550083;5840.299164100293;4831.4685384607665;5004.499550045003;5005.89988200234;5032.220005899055;5105.897882042375;23426.297211083696;23946.880265598735;24072;24189.758102418928;24287.635685464556;2094.413564202026;2363.869987150706;2413.8431001984814;2436.1229957215387;2709.875401708289;11905.28568285897;14016.789748153786;14245.287735613281;14374.856251437457;14467.059641123293;1695.7286834106483;1751.509577318353;1770.672425601255;1776.5203395083383;1799.631075629493;99927;101553.96892062118;104639.47680261549;109450.45274773655;111054;9853.605855765833;11269.380184089954;11347;11570.958613724804;11573.710657233469;14171.149731016172;14383.640408989859;14472.986890917746;14511.346989385504;14518.709625807425;7257.52826066304;8450.746477605682;8486.278666313432;8612.526311052952;8673.479591224546;8585.18440748128;8602.698905538276;8623.568821558896;8667.653293868301;8676.956615216883;3612.5664920209742;4491.214037543416;4524.23088075028;4526.049529598756;4565.20108980927;1960.8235258826774;2001.2295266322571;2018.1826360324092;2026.7770545240012;2052.5073982244153;877.3463769491769;942.505184777997;1006.4313662780505;1021.5556233038652;1073.9624113156;18586;18799.718004229948;18898.244070237037;18962.62074758497;19393.90303048504;918.4672889724078;936.2089034765612;939.3424602778083;993.9950300248453;998.6404894238068;8298.875516867252;10100.74748131288;10159.018991296627;10216.651763636339;10283.125934295518;600351;603482;613729;618470;629709;96228;112401;120476;121082;124282;965.6813251627086;975.5171190260867;996.7259003773875;998.2413365841867;1004.7940172264668;16472.42346517866;16562.83437165613;16805.32778688839;17069.40257090996;17074.48776536706;48897;49243;50340.993180136924;50350.74824625853;51275.1076486553;66008;66151.66924165348;66193;69068;69874.60250795058;7558.546487210785;7729.961350193213;7812.92187078122;7831.608419579112;8124.796880078045;8670.78323041929;9111.587703905905;9830.803383932138;12075.27548347102;12096.879031209928;10030.398176109456;10257;10401.167906567305;10448.007439293255;10559.577616895238;9948.651797187063;10042.347247428894;10229.74425639365;10305.278630495794;10429.322094063862;7325.523840950321;8822.514761687911;9759.902400976183;10323.587056517656;10559.841602375816;7551.811204719706;7884.172161922916;7950.085740139958;8008.6396112173925;8254.669813207405;10295.897041029493;10546.103581195684;10612.8938710615;10805.837912431478;10910.399928004035 05:36:46 INFO - PROCESS | 1679 | |3;mootools.html;1581.9446319378824;1587.6665900160963;1612.9677406451872;1934.7001214811692;1951.0537389366164;8109.553974531398;8563.571821408928;8568.742937711879;8858.424202426846;8899.599518021689;7972.923655306532;8651.307895368356;8663.047064822864;8700.216980471756;8724.738257852274;2157.611629906612;2365.550545396378;2371.845830021052;2372.78644921957;2379.0245999140357;2367.6803631509783;2367.869767162805;2416.5891798394314;2419.891104900275;2424.200013995386;2344.906203751848;2363.3973336799177;2369.8815059247054;2402.771736685012;2409.590369637157;2266.9433264168365;2334.556434277482;2337.8129749620075;2353.1528649686097;2360.3509035015336;2057.77364489906;2367.727711313187;2395.173665085543;2406.2901444073864;2407.7712617301327;8848.159424854628;8915.598798054103;8921.152490513456;8954.328425368058;8957.552122393854;5044.218146187347;5180.740962951875;5248.448912864133;5483.725813709339;5522.337319521643;3412.9488057678905;3467.115885449194;3546.343926373603;3648.963510364889;3654.031681604377;1025.072309559842;1049.6221360310283;1053.0890779475699;1056.7410984308895;1081.7566047639298;11652.242604230783;11712.53149874004;11825.231359961661;11870.109741769316;11917;3910.4720862683744;4546.227141385973;4691.225947718656;4707.8587642370785;4758.809647614126;9231.215346695471;9330.58012389444;9358.204552612968;9363.90636093644;9382.296327775444;5342.679439233659;5402.459754024566;5410.161424979135;5414.052540805344;5438.456154384609;3729.533808273966;4438.511763705989;4983.202687569971;5008.023435430056;5009.248612708123;3715.3126671565847;3807.2766174427325;3822.57951625321;3842.193139440693;3906.5898080701686;1163.4182908545727;1212.1878341511208;1222.9205101668363;1234.7530493901077;1239.3617287097152;777.3780975219802;814.5967745965667;897.5512243878061;914.8993610702818;950.8526178442353;5552.666839989613;5777.075667893117;5914.4381283777975;5941.494972927349;6017;459.6690382924289;461.266586128058;473.3042427631376;478.1345764166869;480.69475882814663;3762.981185094057;3797.468354430382;3810.8094595270127;3838.692904567628;3907.9804600976813;8760.386772925835;10400.47997600117;10509.474526273656;10855.294405863593;10918.563257469612;10557.894421055844;10690.78618427627;10694.572217111385;10789.730256743644;10860.619878304222;7858.410619203582;8140.145284745135;8307.750767477106;8349.958250208832;8367.079621241659;5675.772969081356;5733.569982251264;5756.798512052058;5794.739236734273;5824.126381042877;9947.801043978936;10020.248481364073;10118.949405252926;10183.032611901857;10183.69448916504;9621.711348659552;9832.213422926006;10257.33327333721;10282.971702829656;10309.690709278733;7009.12385951756;7443.627818609156;7471.701131954878;7593.392528597809;7609.124950630749;8499.320054395757;8534.018587862476;8578.270846978077;8597.054324024228;8600.967883853977;7416.8887466686965;7551.886721699069;7603.0876294844975;7618.085829700472;7620.657070431955;7345.70617175307;7393.297636724503;7426.4430167736555;7507.88738168917;7578.507397019176;5915.378885217162;7104.3961263293195;7139.071920650283;7141.64291785419;7187.425005999403;4812.5668689818085;4928.014397120662;5018.347614810052;5020.3724534433195;5100.897982040413;4946.678465899705;5062.569681577107;5062.924056139087;5084.466131056186;5342.572594192533 05:36:46 INFO - PROCESS | 1679 | |4;prototype.html;275.88964414234306;313.30915331693603;330.81143748063596;331.32409883836976;331.84403330434685;4296.441462609856;4768.284757286405;5298.417174110845;5374.731263436827;5510.311211098613;4750.263709125091;4873.220284754431;4883.218685010399;5086.6439349245475;5450.836474905749;3575.982120089409;3723.1622884850963;4120.361343991679;4151.667866570682;4153.626173644371;3473.513708080871;3698.2418604186078;3872.283627528916;3988.24223397555;4124.010237542998;3575.998720358291;3689.446583012556;3749.343864823645;4098.180363927211;4100.343944968806;3198.984005079983;4070.470838790953;4119.134981653856;4121.340585506305;4122.587741225853;3565.893023209308;3576.159602493412;3578.35589593873;3585.64143585642;3586.444101164324;4591.471980722226;4912.606991440676;5030.57240134589;5217.42608313089;5263.131588286142;4666.6500012498855;4666.859994200179;4682.29765535169;4722.488803582854;4741.170295198326;5733.88532229353;5739.110437882135;5757.286550175156;5757.41671040463;5763.942360576382;3600.5859326177565;3749.15643980105;3791.829367678461;3905.7265991380614;3912.902177445558;5801.3038435388025;5802.854928626776;5911.586188966774;6029.125776762345;6035.336113027563;3538.885251145881;3949.308870947573;3953.802309884494;3964.9953546917245;3994.960050399517;3068.3709839482854;3779.829907654162;4001.3997900314603;4002.799860006988;4015.578364271768;4662;4662.836800711959;4669.571822652202;4671.15919134559;4709.740964246931;5057.21613149955;5058.9494105058475;5753.827385178452;5772.797952071657;5807.82576522705;1206.879312068786;1245.3897590180927;1377.5109836008273;1470.6029372069695;1483.7551803952226;813.7680760983092;954.1317401164906;963.816874793787;977.3940157102642;994.0208894239169;4598.471175814757;5773.566982476331;5812.38969908162;5816.651000939873;5849.883002340014;5715.828525144252;6036.913355595947;6363.7772677956045;6408.1349017881985;6418.486521078303;4385.451818522684;4855.223164293767;5056.82301119459;5100.081985242692;5509.47659972302;2807.6490438695164;3292.2921571862166;3391.236971681352;3433.6051354094097;3472.965270347315;6396.68867882083;6500.252470965899;6790.898136527857;6808.863822723518;6811.523193376416;5311.760970756325;5548.334199896039;6562.704678289489;6671.933280667228;6675.766348177791;6041.7916416716835;6358.904616430757;6605.900911486331;6733.6296503692765;6765.932340676629;5653.830385088454;5910.763569457174;6482.448991835558;6490.253620833664;6506.869862602816;5295.405251426851;6269.3417191196095;6376.330485299162;6388.680565971776;6394.072859435449;5832.850260859089;6274.937250627435;6445;6482.967585162044;6786.151731033621;3378.35811195872;4152.231837110145;4158.69574989884;4210.368444733192;4212.1575684863765;5344.625876188629;5604.607677462538;6468.482521398183;6522.478201743944;6563.523207278324;5014.693241108985;5237.188235823453;5591.720413979366;5696.177223288508;5790.420957904176;4700.318453824245;5710.401087695452;5725.0248664210785;5754.769809207585;5757.395473475225;4510.458744950627;5232.415795180314;5351.812686555952;5577.051901176891;5613.214150019082;5018.548330650259;5465.059903734117;5606.467385598362;5704.229928959538;5710.8572285691525;4729.952700472952;5315.867103322448;5581.358143813513;5613.382527921844;5629.789595237044 05:36:46 INFO - PROCESS | 1679 | |5;yui.html;632.2981490545496;660.9074729537864;753.5629334985707;765.3571000359697;770.1451388958255;14635.414583416665;14638.414463421477;14772;14829.777553336695;15175.08949463033;9875.308728389016;13533.458661653547;13591.456341746318;13653.317334133304;14287.785538229236;742.6546655805049;897.9102089791002;913.2146354135439;932.8181004704086;952.3190918493276;803.586153131138;924.2559739409772;943.2077055273569;948.2177203806853;950.1211379473993;990.2078337330144;991.1080027974808;991.1476130527741;991.6479649724353;992.8411454167334;916.9174774270315;955.9713208603753;963.3593660215948;981.7643765496301;984.4142735072656;707.2609123465991;721.5237942957675;733.6185183704497;734.071399679406;735.5219107580116;14625.195614241213;14781.556553303419;14811.851881481154;14862.182579958098;14905.552833415015;12192.695182620417;12370.690732731753;12390.194637348544;12439.75120497594;12653.36733163338;9754.115420065193;9860.211183105333;10098.596056157745;10103.19174466041;10403.947980260125;3446.758726889119;3476.66971637694;3488.4244099723514;3523.119220194951;3587.515685382466;74533;75112.87330690044;76140.23859761388;76514;79201.81197282045;9191.724248272563;9194.724158275263;9322;9588.856167157499;9644.90355096454;21475.03362348698;21523.67714484284;21553.245636402964;21566.56866862654;21660.566788664455;7207.243239459886;7229.8554028919125;7329.560226386434;7377.557346559116;7618.047744031996;3824.2160357126722;3904.648581627667;3950.111224974415;3994.6205110514456;4216.945763559111;3508.9648553676725;3511.877084302037;3593.299306635181;3594.802285874302;3599.820008999592;557.8075563930438;628.8710814283061;636.9426751592379;648.2771709543824;659.4724220623577;565.9009673307237;568.366271607155;584.2967749011584;589.4223660812428;602.1660000898787;7765.7281995131025;7829.060512738508;7894.0921793993275;7966.561839098789;8162.795930101676;509.8266589359561;568.6275489554348;590.4066413254737;592.3484167415894;595.1429940885112;3821;3830.9616903830606;3836.9808150959066;3841.3277676407074;3873.283442563135;90887.54556227177;91357;92088.61867072;92312;95294.52352738331;71937;74649.62675186589;75015.62492187505;77093;77611.61194194105;67633.66183169052;67702.66148669225;67922.99999999901;68172;72001.91997120048;6352.9047064293145;7656.961715191388;7669.118051423934;7697.230276972258;7826.41301902371;29693.85153074221;44459.55540444554;45559.31661025154;45695.77152114218;45890.770546147054;64456.67771661111;64890.67554662196;65421.67289163524;65637.01544476741;65772.67113664401;10446.52990615409;10600.416977066336;10972.615958441736;11080.5567777288;11358;11744.354060526412;12266.405367302194;12329.815052774395;12366.258024518198;12611.243325400505;10520.842187367349;11095.500702468247;11375.488103035548;11582.826257605973;11828.784441490512;11267.943660281973;11293.096552275634;11383.772324553629;11460.197786155222;12104.092193085378;9556.61773529051;11347.14896382791;11546.76906461883;11703.76592468163;11835.349055802013;8934.955325223333;9417.717468475958;9450.007749186236;9863.358881672668;10136.44249566281;9594.280428967715;9689.176420004096;9756.121844493271;9859.915409304827;9988.850167247352 05:36:46 INFO - PROCESS | 1679 | __end_tp_report 05:36:46 INFO - PROCESS | 1679 | __start_cc_report 05:36:46 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,416 05:36:46 INFO - PROCESS | 1679 | __end_cc_report 05:36:46 INFO - PROCESS | 1679 | __startTimestamp1461847006372__endTimestamp 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | ------- Summary: start ------- 05:36:46 INFO - PROCESS | 1679 | Number of tests: 6 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | [#0] dojo.html Cycles:165 Average:5684.51 Median:5982.79 stddev:765.10 (12.8%) stddev-sans-first:743.41 05:36:46 INFO - PROCESS | 1679 | Values: 3259.1 3440.9 3474.5 3531.3 3546.2 5501.7 5667.0 5727.1 5730.3 5740.5 5604.7 5640.0 5728.9 5756.5 5884.2 5066.6 5103.2 5145.4 5209.2 5250.0 687.2 5082.4 5152.6 5166.1 5242.5 3735.9 5156.4 5191.1 5191.3 5225.5 5183.3 5193.5 5204.0 5205.9 5236.3 5069.4 5074.4 5162.5 5172.5 5188.6 5517.6 5624.8 5681.8 5689.4 5695.6 5981.1 6111.3 6177.3 6212.7 6256.0 6118.9 6239.9 6251.2 6253.0 6290.7 5782.2 5806.2 5807.9 5812.5 5878.1 6203.4 6224.2 6299.0 6313.9 6324.7 5099.3 6124.3 6128.3 6129.1 6161.0 6158.8 6242.4 6292.7 6294.9 6304.1 6103.9 6152.7 6185.1 6235.7 6255.8 6223.5 6235.9 6253.9 6256.5 6267.2 4394.9 4878.3 4940.0 4941.0 4969.5 4287.5 4379.0 4396.8 4470.2 4524.1 5725.3 5734.3 5772.6 5778.8 5783.7 5126.7 5988.3 6250.1 6251.7 6291.4 5810.8 5812.2 5823.2 5838.8 5880.8 5296.1 5365.2 5424.6 5427.3 5446.0 6247.3 6263.6 6305.7 6313.5 6325.1 6205.2 6267.1 6268.8 6296.4 6305.1 6221.5 6257.8 6259.9 6283.7 6316.7 5072.8 5273.3 6089.8 6137.7 6165.7 6131.6 6180.3 6254.7 6258.0 6270.3 5964.4 6093.8 6095.8 6112.6 6118.4 6035.4 6108.9 6141.6 6145.5 6165.5 5945.9 5980.5 5982.8 6004.6 6020.5 6015.9 6109.6 6117.9 6154.1 6166.2 6028.3 6056.4 6060.7 6066.8 6082.6 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | [#1] ext.html Cycles:180 Average:55499.18 Median:36668.45 stddev:114002.04 (310.9%) stddev-sans-first:114247.08 05:36:46 INFO - PROCESS | 1679 | Values: 506.8 517.7 520.3 533.1 556.4 36772.9 38577.4 38592.0 38594.0 38595.4 39096.4 39866.8 39887.2 39958.6 40088.8 9327.1 9426.7 9445.1 9449.0 9594.8 9483.8 9530.1 9706.0 9715.6 9724.3 9674.8 9710.5 9720.6 9759.5 9806.8 9637.3 9708.0 9709.3 9711.6 9744.3 9524.7 9572.6 9607.7 9609.6 9613.3 38110.0 38187.4 38394.6 38477.4 38516.4 2743.8 2924.8 3018.1 3189.5 3434.1 23647.0 26814.5 26847.2 27372.0 27994.3 2749.8 2759.9 2768.5 2883.3 2970.6 248018.0 295570.6 313723.9 314181.0 314639.0 32774.7 58377.0 61873.4 61896.0 62072.7 34684.5 34713.5 34768.0 34878.7 34945.3 14943.3 15089.0 15165.3 15183.5 15211.4 12560.7 12823.9 12842.8 12877.7 12883.0 8005.1 8061.8 8134.3 8187.4 8209.6 3036.5 3325.5 3363.8 3390.3 3449.8 931.8 961.5 1028.1 1071.1 1133.8 25492.5 25558.0 27500.7 27529.4 27848.6 1065.7 1071.7 1089.7 1119.5 1180.7 14280.7 14463.4 14574.9 14650.0 15009.5 500452.0 502418.0 504981.0 512015.0 512735.0 57175.4 58415.7 58683.7 59122.0 59148.4 1261.5 1474.2 1485.9 1503.5 1509.2 27536.6 28241.0 28377.6 28674.3 28792.3 46628.0 48197.0 48877.5 49520.8 49633.3 449764.0 450964.0 451704.0 453187.0 453966.0 25994.4 26036.9 26056.3 26068.3 26454.6 44281.6 44733.3 44856.3 45314.5 45415.8 33512.2 36977.8 37022.4 37332.6 38077.2 33897.8 36561.8 36564.0 37158.9 37523.8 36210.9 36473.6 36480.9 36981.1 37035.0 31671.7 31831.2 32236.0 32263.2 32380.4 19763.4 21110.5 22024.1 24144.4 24559.8 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | [#2] jquery.html Cycles:180 Average:33919.37 Median:396.73 stddev:101606.30 (25611.1%) stddev-sans-first:101860.14 05:36:46 INFO - PROCESS | 1679 | Values: 390.6 396.6 396.8 397.8 407.6 14031.8 19033.9 19580.6 19763.4 19782.0 14733.4 16469.7 18237.8 19453.4 19529.9 5594.9 5719.8 5868.2 5904.2 5925.1 5822.2 5839.1 5866.2 5881.5 5888.4 4785.4 5796.9 5801.7 5802.4 5802.6 4928.0 5746.2 5778.2 5801.7 5840.3 4831.5 5004.5 5005.9 5032.2 5105.9 23426.3 23946.9 24072.0 24189.8 24287.6 2094.4 2363.9 2413.8 2436.1 2709.9 11905.3 14016.8 14245.3 14374.9 14467.1 1695.7 1751.5 1770.7 1776.5 1799.6 99927.0 101554.0 104639.5 109450.5 111054.0 9853.6 11269.4 11347.0 11571.0 11573.7 14171.1 14383.6 14473.0 14511.3 14518.7 7257.5 8450.7 8486.3 8612.5 8673.5 8585.2 8602.7 8623.6 8667.7 8677.0 3612.6 4491.2 4524.2 4526.0 4565.2 1960.8 2001.2 2018.2 2026.8 2052.5 877.3 942.5 1006.4 1021.6 1074.0 18586.0 18799.7 18898.2 18962.6 19393.9 918.5 936.2 939.3 994.0 998.6 8298.9 10100.7 10159.0 10216.7 10283.1 600351.0 603482.0 613729.0 618470.0 629709.0 96228.0 112401.0 120476.0 121082.0 124282.0 965.7 975.5 996.7 998.2 1004.8 16472.4 16562.8 16805.3 17069.4 17074.5 48897.0 49243.0 50341.0 50350.7 51275.1 66008.0 66151.7 66193.0 69068.0 69874.6 7558.5 7730.0 7812.9 7831.6 8124.8 8670.8 9111.6 9830.8 12075.3 12096.9 10030.4 10257.0 10401.2 10448.0 10559.6 9948.7 10042.3 10229.7 10305.3 10429.3 7325.5 8822.5 9759.9 10323.6 10559.8 7551.8 7884.2 7950.1 8008.6 8254.7 10295.9 10546.1 10612.9 10805.8 10910.4 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | [#3] mootools.html Cycles:180 Average:5674.30 Median:4964.94 stddev:3207.88 (64.6%) stddev-sans-first:3202.14 05:36:46 INFO - PROCESS | 1679 | Values: 1581.9 1587.7 1613.0 1934.7 1951.1 8109.6 8563.6 8568.7 8858.4 8899.6 7972.9 8651.3 8663.0 8700.2 8724.7 2157.6 2365.6 2371.8 2372.8 2379.0 2367.7 2367.9 2416.6 2419.9 2424.2 2344.9 2363.4 2369.9 2402.8 2409.6 2266.9 2334.6 2337.8 2353.2 2360.4 2057.8 2367.7 2395.2 2406.3 2407.8 8848.2 8915.6 8921.2 8954.3 8957.6 5044.2 5180.7 5248.4 5483.7 5522.3 3412.9 3467.1 3546.3 3649.0 3654.0 1025.1 1049.6 1053.1 1056.7 1081.8 11652.2 11712.5 11825.2 11870.1 11917.0 3910.5 4546.2 4691.2 4707.9 4758.8 9231.2 9330.6 9358.2 9363.9 9382.3 5342.7 5402.5 5410.2 5414.1 5438.5 3729.5 4438.5 4983.2 5008.0 5009.2 3715.3 3807.3 3822.6 3842.2 3906.6 1163.4 1212.2 1222.9 1234.8 1239.4 777.4 814.6 897.6 914.9 950.9 5552.7 5777.1 5914.4 5941.5 6017.0 459.7 461.3 473.3 478.1 480.7 3763.0 3797.5 3810.8 3838.7 3908.0 8760.4 10400.5 10509.5 10855.3 10918.6 10557.9 10690.8 10694.6 10789.7 10860.6 7858.4 8140.1 8307.8 8350.0 8367.1 5675.8 5733.6 5756.8 5794.7 5824.1 9947.8 10020.2 10118.9 10183.0 10183.7 9621.7 9832.2 10257.3 10283.0 10309.7 7009.1 7443.6 7471.7 7593.4 7609.1 8499.3 8534.0 8578.3 8597.1 8601.0 7416.9 7551.9 7603.1 7618.1 7620.7 7345.7 7393.3 7426.4 7507.9 7578.5 5915.4 7104.4 7139.1 7141.6 7187.4 4812.6 4928.0 5018.3 5020.4 5100.9 4946.7 5062.6 5062.9 5084.5 5342.6 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | [#4] prototype.html Cycles:180 Average:4724.40 Median:5158.75 stddev:1532.66 (29.7%) stddev-sans-first:1500.15 05:36:46 INFO - PROCESS | 1679 | Values: 275.9 313.3 330.8 331.3 331.8 4296.4 4768.3 5298.4 5374.7 5510.3 4750.3 4873.2 4883.2 5086.6 5450.8 3576.0 3723.2 4120.4 4151.7 4153.6 3473.5 3698.2 3872.3 3988.2 4124.0 3576.0 3689.4 3749.3 4098.2 4100.3 3199.0 4070.5 4119.1 4121.3 4122.6 3565.9 3576.2 3578.4 3585.6 3586.4 4591.5 4912.6 5030.6 5217.4 5263.1 4666.7 4666.9 4682.3 4722.5 4741.2 5733.9 5739.1 5757.3 5757.4 5763.9 3600.6 3749.2 3791.8 3905.7 3912.9 5801.3 5802.9 5911.6 6029.1 6035.3 3538.9 3949.3 3953.8 3965.0 3995.0 3068.4 3779.8 4001.4 4002.8 4015.6 4662.0 4662.8 4669.6 4671.2 4709.7 5057.2 5058.9 5753.8 5772.8 5807.8 1206.9 1245.4 1377.5 1470.6 1483.8 813.8 954.1 963.8 977.4 994.0 4598.5 5773.6 5812.4 5816.7 5849.9 5715.8 6036.9 6363.8 6408.1 6418.5 4385.5 4855.2 5056.8 5100.1 5509.5 2807.6 3292.3 3391.2 3433.6 3473.0 6396.7 6500.3 6790.9 6808.9 6811.5 5311.8 5548.3 6562.7 6671.9 6675.8 6041.8 6358.9 6605.9 6733.6 6765.9 5653.8 5910.8 6482.4 6490.3 6506.9 5295.4 6269.3 6376.3 6388.7 6394.1 5832.9 6274.9 6445.0 6483.0 6786.2 3378.4 4152.2 4158.7 4210.4 4212.2 5344.6 5604.6 6468.5 6522.5 6563.5 5014.7 5237.2 5591.7 5696.2 5790.4 4700.3 5710.4 5725.0 5754.8 5757.4 4510.5 5232.4 5351.8 5577.1 5613.2 5018.5 5465.1 5606.5 5704.2 5710.9 4730.0 5315.9 5581.4 5613.4 5629.8 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - PROCESS | 1679 | [#5] yui.html Cycles:180 Average:17699.35 Median:3494.92 stddev:24816.99 (710.1%) stddev-sans-first:24853.52 05:36:46 INFO - PROCESS | 1679 | Values: 632.3 660.9 753.6 765.4 770.1 14635.4 14638.4 14772.0 14829.8 15175.1 9875.3 13533.5 13591.5 13653.3 14287.8 742.7 897.9 913.2 932.8 952.3 803.6 924.3 943.2 948.2 950.1 990.2 991.1 991.1 991.6 992.8 916.9 956.0 963.4 981.8 984.4 707.3 721.5 733.6 734.1 735.5 14625.2 14781.6 14811.9 14862.2 14905.6 12192.7 12370.7 12390.2 12439.8 12653.4 9754.1 9860.2 10098.6 10103.2 10403.9 3446.8 3476.7 3488.4 3523.1 3587.5 74533.0 75112.9 76140.2 76514.0 79201.8 9191.7 9194.7 9322.0 9588.9 9644.9 21475.0 21523.7 21553.2 21566.6 21660.6 7207.2 7229.9 7329.6 7377.6 7618.0 3824.2 3904.6 3950.1 3994.6 4216.9 3509.0 3511.9 3593.3 3594.8 3599.8 557.8 628.9 636.9 648.3 659.5 565.9 568.4 584.3 589.4 602.2 7765.7 7829.1 7894.1 7966.6 8162.8 509.8 568.6 590.4 592.3 595.1 3821.0 3831.0 3837.0 3841.3 3873.3 90887.5 91357.0 92088.6 92312.0 95294.5 71937.0 74649.6 75015.6 77093.0 77611.6 67633.7 67702.7 67923.0 68172.0 72001.9 6352.9 7657.0 7669.1 7697.2 7826.4 29693.9 44459.6 45559.3 45695.8 45890.8 64456.7 64890.7 65421.7 65637.0 65772.7 10446.5 10600.4 10972.6 11080.6 11358.0 11744.4 12266.4 12329.8 12366.3 12611.2 10520.8 11095.5 11375.5 11582.8 11828.8 11267.9 11293.1 11383.8 11460.2 12104.1 9556.6 11347.1 11546.8 11703.8 11835.3 8935.0 9417.7 9450.0 9863.4 10136.4 9594.3 9689.2 9756.1 9859.9 9988.9 05:36:46 INFO - PROCESS | 1679 | -------- Summary: end -------- 05:36:46 INFO - PROCESS | 1679 | 05:36:46 INFO - TEST-INFO | 1679: exit 0 05:36:46 INFO - TEST-OK | dromaeo_css | took 1112160ms 05:36:46 INFO - TEST-START | kraken 05:36:46 INFO - operating with platform_type : mac_ 05:36:46 INFO - Initialising browser for kraken test... 05:36:46 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmp39y2Ce/profile http://localhost:49270/getInfo.html) 05:36:49 INFO - PROCESS | 1687 | __metrics Screen width/height:1600/1200 05:36:49 INFO - PROCESS | 1687 | colorDepth:24 05:36:49 INFO - PROCESS | 1687 | Browser inner width/height: 1024/689 05:36:49 INFO - PROCESS | 1687 | __metrics 05:36:49 ERROR - PROCESS | 1687 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:36:50 INFO - TEST-INFO | 1687: exit 0 05:36:50 INFO - Browser initialized. 05:36:50 INFO - Running cycle 1/1 for kraken test... 05:36:50 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmp39y2Ce/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:02 INFO - PROCESS | 1691 | RSS: Main: 249683968 05:37:02 INFO - PROCESS | 1691 | 05:37:05 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:06 INFO - PROCESS | 1691 | RSS: Main: 261935104 05:37:06 INFO - PROCESS | 1691 | 05:37:09 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:37:09 INFO - PROCESS | 1691 | RSS: Main: 271032320 05:37:09 INFO - PROCESS | 1691 | 05:37:13 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:37:14 INFO - PROCESS | 1691 | RSS: Main: 273993728 05:37:14 INFO - PROCESS | 1691 | 05:37:16 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:37:16 INFO - PROCESS | 1691 | RSS: Main: 275959808 05:37:16 INFO - PROCESS | 1691 | 05:37:18 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:37:19 INFO - PROCESS | 1691 | RSS: Main: 276643840 05:37:19 INFO - PROCESS | 1691 | 05:37:22 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:37:22 INFO - PROCESS | 1691 | RSS: Main: 277676032 05:37:22 INFO - PROCESS | 1691 | 05:37:26 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:37:26 INFO - PROCESS | 1691 | RSS: Main: 277282816 05:37:26 INFO - PROCESS | 1691 | 05:37:29 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:37:30 INFO - PROCESS | 1691 | RSS: Main: 277213184 05:37:30 INFO - PROCESS | 1691 | 05:37:31 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:37:32 INFO - PROCESS | 1691 | RSS: Main: 276717568 05:37:32 INFO - PROCESS | 1691 | 05:37:34 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:37:34 INFO - PROCESS | 1691 | RSS: Main: 276713472 05:37:34 INFO - PROCESS | 1691 | 05:37:36 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:37:37 INFO - PROCESS | 1691 | RSS: Main: 276987904 05:37:37 INFO - PROCESS | 1691 | 05:37:40 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:37:41 INFO - PROCESS | 1691 | RSS: Main: 277278720 05:37:41 INFO - PROCESS | 1691 | 05:37:44 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:37:45 INFO - PROCESS | 1691 | RSS: Main: 275308544 05:37:45 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:37:47 INFO - PROCESS | 1691 | RSS: Main: 277110784 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | __start_tp_report 05:37:47 INFO - PROCESS | 1691 | _x_x_mozilla_page_load 05:37:47 INFO - PROCESS | 1691 | _x_x_mozilla_page_load_details 05:37:47 INFO - PROCESS | 1691 | |i|pagename|runs| 05:37:47 INFO - PROCESS | 1691 | |0;ai-astar;115;121;173;177;121;124;120;117;114;114 05:37:47 INFO - PROCESS | 1691 | |1;audio-beat-detection;145;125;187;198;154;143;120;160;161;124 05:37:47 INFO - PROCESS | 1691 | |2;audio-dft;206;261;273;217;194;199;197;229;177;173 05:37:47 INFO - PROCESS | 1691 | |3;audio-fft;66;105;115;175;58;57;55;58;126;57 05:37:47 INFO - PROCESS | 1691 | |4;audio-oscillator;79;97;127;77;80;81;118;82;81;119 05:37:47 INFO - PROCESS | 1691 | |5;imaging-gaussian-blur;120;131;89;86;89;85;105;132;108;87 05:37:47 INFO - PROCESS | 1691 | |6;imaging-darkroom;131;104;152;110;142;94;155;154;120;95 05:37:47 INFO - PROCESS | 1691 | |7;imaging-desaturate;91;90;92;92;93;93;89;95;91;91 05:37:47 INFO - PROCESS | 1691 | |8;json-parse-financial;59;61;62;61;61;62;60;76;63;61 05:37:47 INFO - PROCESS | 1691 | |9;json-stringify-tinderbox;62;66;68;66;75;71;65;67;69;69 05:37:47 INFO - PROCESS | 1691 | |10;stanford-crypto-aes;83;84;83;82;85;82;85;82;86;82 05:37:47 INFO - PROCESS | 1691 | |11;stanford-crypto-ccm;154;167;152;138;150;143;162;167;195;147 05:37:47 INFO - PROCESS | 1691 | |12;stanford-crypto-pbkdf2;201;246;275;213;199;246;272;221;199;200 05:37:47 INFO - PROCESS | 1691 | |13;stanford-crypto-sha256-iterative;71;67;78;100;61;63;99;100;96;85 05:37:47 INFO - PROCESS | 1691 | __end_tp_report 05:37:47 INFO - PROCESS | 1691 | __start_cc_report 05:37:47 INFO - PROCESS | 1691 | _x_x_mozilla_cycle_collect,1070 05:37:47 INFO - PROCESS | 1691 | __end_cc_report 05:37:47 INFO - PROCESS | 1691 | __startTimestamp1461847067575__endTimestamp 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | ------- Summary: start ------- 05:37:47 INFO - PROCESS | 1691 | Number of tests: 14 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#0] ai-astar Cycles:10 Average:129.60 Median:121.00 stddev:24.18 (20.0%) stddev-sans-first:25.06 05:37:47 INFO - PROCESS | 1691 | Values: 115.0 121.0 173.0 177.0 121.0 124.0 120.0 117.0 114.0 114.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#1] audio-beat-detection Cycles:10 Average:151.70 Median:157.00 stddev:26.15 (16.7%) stddev-sans-first:27.63 05:37:47 INFO - PROCESS | 1691 | Values: 145.0 125.0 187.0 198.0 154.0 143.0 120.0 160.0 161.0 124.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#2] audio-dft Cycles:10 Average:212.60 Median:211.50 stddev:33.22 (15.7%) stddev-sans-first:35.15 05:37:47 INFO - PROCESS | 1691 | Values: 206.0 261.0 273.0 217.0 194.0 199.0 197.0 229.0 177.0 173.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#3] audio-fft Cycles:10 Average:87.20 Median:57.00 stddev:41.26 (72.4%) stddev-sans-first:43.04 05:37:47 INFO - PROCESS | 1691 | Values: 66.0 105.0 115.0 175.0 58.0 57.0 55.0 58.0 126.0 57.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:80.50 stddev:19.69 (24.5%) stddev-sans-first:20.12 05:37:47 INFO - PROCESS | 1691 | Values: 79.0 97.0 127.0 77.0 80.0 81.0 118.0 82.0 81.0 119.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#5] imaging-gaussian-blur Cycles:10 Average:103.20 Median:85.50 stddev:18.87 (22.1%) stddev-sans-first:19.01 05:37:47 INFO - PROCESS | 1691 | Values: 120.0 131.0 89.0 86.0 89.0 85.0 105.0 132.0 108.0 87.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#6] imaging-darkroom Cycles:10 Average:125.70 Median:153.00 stddev:24.36 (15.9%) stddev-sans-first:25.77 05:37:47 INFO - PROCESS | 1691 | Values: 131.0 104.0 152.0 110.0 142.0 94.0 155.0 154.0 120.0 95.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.00 stddev:1.70 (1.9%) stddev-sans-first:1.79 05:37:47 INFO - PROCESS | 1691 | Values: 91.0 90.0 92.0 92.0 93.0 93.0 89.0 95.0 91.0 91.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:61.50 stddev:4.84 (7.9%) stddev-sans-first:4.95 05:37:47 INFO - PROCESS | 1691 | Values: 59.0 61.0 62.0 61.0 61.0 62.0 60.0 76.0 63.0 61.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.50 stddev:3.55 (5.2%) stddev-sans-first:3.09 05:37:47 INFO - PROCESS | 1691 | Values: 62.0 66.0 68.0 66.0 75.0 71.0 65.0 67.0 69.0 69.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.50 stddev:1.51 (1.8%) stddev-sans-first:1.59 05:37:47 INFO - PROCESS | 1691 | Values: 83.0 84.0 83.0 82.0 85.0 82.0 85.0 82.0 86.0 82.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#11] stanford-crypto-ccm Cycles:10 Average:157.50 Median:158.00 stddev:16.35 (10.3%) stddev-sans-first:17.29 05:37:47 INFO - PROCESS | 1691 | Values: 154.0 167.0 152.0 138.0 150.0 143.0 162.0 167.0 195.0 147.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:227.20 Median:233.50 stddev:30.29 (13.0%) stddev-sans-first:30.60 05:37:47 INFO - PROCESS | 1691 | Values: 201.0 246.0 275.0 213.0 199.0 246.0 272.0 221.0 199.0 200.0 05:37:47 INFO - PROCESS | 1691 | 05:37:47 INFO - PROCESS | 1691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.00 Median:74.50 stddev:16.01 (21.5%) stddev-sans-first:16.48 05:37:47 INFO - PROCESS | 1691 | Values: 71.0 67.0 78.0 100.0 61.0 63.0 99.0 100.0 96.0 85.0 05:37:47 INFO - PROCESS | 1691 | -------- Summary: end -------- 05:37:47 INFO - PROCESS | 1691 | 05:37:48 INFO - TEST-INFO | 1691: exit 0 05:37:48 INFO - TEST-OK | kraken | took 61412ms 05:37:48 INFO - SUITE-END | took 1173s 05:37:48 INFO - Completed test suite (00:19:33) 05:37:48 INFO - javascript benchmark 05:37:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3259.103746469719, 3440.913977150571, 3474.4961980512826, 3531.2937412517504, 3546.237558924831, 5501.669899806009, 5666.971665141678, 5727.055035919078, 5730.341010783751, 5740.454656807607, 5604.663720176793, 5640.04119299719, 5728.942710572893, 5756.539476841864, 5884.235049443566, 5066.645334826564, 5103.234514822788, 5145.434002259767, 5209.166533354664, 5249.973750131263, 687.1866292489663, 5082.390113186404, 5152.562032227265, 5166.121759300909, 5242.501962313574, 3735.8692445764405, 5156.407013193493, 5191.091558977164, 5191.299174611417, 5225.4513276105845, 5183.3261675981885, 5193.506616871391, 5203.973980130113, 5205.869853253661, 5236.345456817898, 5069.417017043051, 5074.416442109168, 5162.535371816554, 5172.482751724835, 5188.584913206934, 5517.641353312062, 5624.803131890405, 5681.835223779157, 5689.431056894319, 5695.629784064023, 5981.072933695241, 6111.327753947062, 6177.2587289525545, 6212.689365531751, 6255.999040153599, 6118.923762351576, 6239.937600623981, 6251.187345645082, 6252.968735156341, 6290.716917738667, 5782.190493330937, 5806.187133801272, 5807.941920580783, 5812.534997200214, 5878.118282257653, 6203.410675985774, 6224.221972253468, 6298.968505157445, 6313.905291420632, 6324.747010119544, 5099.3370861787735, 6124.295705993779, 6128.319797384577, 6129.136520723041, 6161.0, 6158.753649854045, 6242.406971337716, 6292.748290068437, 6294.937050629526, 6304.117423560705, 6103.85289575482, 6152.661603611755, 6185.134081228542, 6235.719392627343, 6255.843603909848, 6223.502119830493, 6235.937640623626, 6253.937460625336, 6256.499480041586, 6267.2165979252595, 4394.868153955386, 4878.317035615017, 4940.011997600423, 4941.011797640486, 4969.503049695001, 4287.520805322159, 4379.0, 4396.846110386121, 4470.240059189946, 4524.117797029613, 5725.2843394575675, 5734.283214598176, 5772.624779389326, 5778.7688492460675, 5783.681897495678, 5126.718030508283, 5988.28140623128, 6250.124982502453, 6251.6561589113035, 6291.370862913672, 5810.82567522975, 5812.157237200582, 5823.155642431823, 5838.824835254949, 5880.794172203952, 5296.09966305729, 5365.248865158881, 5424.566034717213, 5427.348718153847, 5446.0, 6247.312795592481, 6263.592866463757, 6305.716242769086, 6313.463355614732, 6325.05124231369, 6205.162303089206, 6267.122602835516, 6268.811935641938, 6296.401841825019, 6305.054241863757, 6221.5333849962335, 6257.812265632038, 6259.937400625936, 6283.717232724447, 6316.652584107918, 5072.82245121419, 5273.34083239595, 6089.847753806013, 6137.711080673109, 6165.722542485689, 6131.601445906002, 6180.320164781961, 6254.718537665907, 6258.030005348996, 6270.341614130633, 5964.433378829004, 6093.847653808691, 6095.847603809941, 6112.625067133501, 6118.357572455006, 6035.39646035393, 6108.949416342483, 6141.631502109888, 6145.477634401126, 6165.475934545614, 5945.851353716192, 5980.461758441761, 5982.790602328898, 6004.609700369462, 6020.548458865515, 6015.939840601538, 6109.572329936862, 6117.9187529241, 6154.107654390178, 6166.1983942087245, 6028.306744724413, 6056.394360563909, 6060.666663333559, 6066.817995460143, 6082.60463069899], "value": 5638.103498251143, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.84794561631503, 517.6842126302955, 520.2508387921393, 533.1070456984551, 556.4491153757781, 36772.896813095584, 38577.42133867991, 38592.0, 38594.0, 38595.421068683994, 39096.413553796716, 39866.80066599663, 39887.20225595494, 39958.60041399585, 40088.79955600218, 9327.113924177193, 9426.717198484022, 9445.102715242076, 9449.0, 9594.808103837919, 9483.81032379352, 9530.094641009127, 9706.0, 9715.562799673997, 9724.270679699015, 9674.758131046745, 9710.465924374157, 9720.61117555297, 9759.463229522373, 9806.754831129243, 9637.277204209677, 9708.029197080235, 9709.320347575673, 9711.61153553857, 9744.269179811543, 9524.714258572254, 9572.5692343844, 9607.711768646952, 9609.615615375376, 9613.279004074722, 38110.0, 38187.42718859219, 38394.616053839665, 38477.42283865744, 38516.42225366622, 2743.7530622243894, 2924.780641451878, 3018.1096576509763, 3189.4737368334195, 3434.1414646338417, 23647.0, 26814.46371072587, 26847.194584162506, 27372.0, 27994.3001424964, 2749.848758318292, 2759.9448011039867, 2768.5431903735857, 2883.2647674842783, 2970.5841182234503, 248018.0, 295570.5664415035, 313723.86276137177, 314181.0, 314639.0, 32774.67225327764, 58377.0, 61873.381266187665, 61896.0, 62072.68963655153, 34684.47973280403, 34713.479297810554, 34767.9569612912, 34878.65121348805, 34945.30109397849, 14943.252837358088, 15089.0, 15165.317560709795, 15183.544493665207, 15211.391544338103, 12560.685982850502, 12823.93588032054, 12842.807357889638, 12877.742445151045, 12883.033772467103, 8005.079415867133, 8061.838763224702, 8134.349252059821, 8187.38594605407, 8209.589520524069, 3036.5293379525765, 3325.534425180477, 3363.830329437499, 3390.3219356128875, 3449.7757645752945, 931.8276118918022, 961.4663861556903, 1028.095276156977, 1071.0681706914877, 1133.7845809296373, 25492.47045177258, 25558.0, 27500.724992750216, 27529.44941101167, 27848.582271265946, 1065.674969134406, 1071.6784964510616, 1089.6839916424149, 1119.518606998982, 1180.7048237940514, 14280.714385712228, 14463.421463141356, 14574.927125364517, 14650.0, 15009.549713508612, 500452.0, 502418.0, 504981.0, 512015.0, 512735.0, 57175.42824571784, 58415.70792146012, 58683.70658146682, 59122.0, 59148.40851591515, 1261.4575732435142, 1474.2481334519473, 1485.9405623774928, 1503.503843731566, 1509.2378348933937, 27536.586951195346, 28241.0, 28377.574336385384, 28674.283142920758, 28792.280192995342, 46628.0, 48197.0, 48877.511224888716, 49520.75239623779, 49633.255501168234, 449764.0, 450964.0, 451704.0, 453187.0, 453966.0, 25994.350141245865, 26036.8698156508, 26056.34859128537, 26068.34829129287, 26454.603180952687, 44281.557184427744, 44733.329000064376, 44856.32715509335, 45314.54685453235, 45415.77292113518, 33512.15683137353, 36977.815110924275, 37022.44466332953, 37332.62667373291, 38077.238455231294, 33897.813576524706, 36561.81719091388, 36564.0, 37158.88523344304, 37523.812380939016, 36210.913672589864, 36473.63526364809, 36480.90557283286, 36981.075473113386, 37035.0, 31671.683283166873, 31831.204219894687, 32236.0, 32263.193420164684, 32380.35239295248, 19763.40709778709, 21110.47223819417, 22024.0682182625, 24144.396390090387, 24559.754402455746], "value": 17697.945028921, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.6425620557188, 396.6271704597677, 396.8273800896612, 397.7752569798064, 407.63720288942847, 14031.78952315716, 19033.904830475847, 19580.608387832235, 19763.430754001995, 19782.0, 14733.410663573444, 16469.68242540597, 18237.81454205476, 19453.416397508063, 19529.902350488228, 5594.944050559503, 5719.771209151629, 5868.207791948096, 5904.2324497815225, 5925.051991681332, 5822.243108395903, 5839.0949402842625, 5866.237389139427, 5881.500072493822, 5888.440598143169, 4785.401824771903, 5796.942030579704, 5801.7099145042575, 5802.448767367115, 5802.564807639444, 4927.975360123194, 5746.195532625415, 5778.162166485878, 5801.680907550083, 5840.299164100293, 4831.4685384607665, 5004.499550045003, 5005.89988200234, 5032.220005899055, 5105.897882042375, 23426.297211083696, 23946.880265598735, 24072.0, 24189.758102418928, 24287.635685464556, 2094.413564202026, 2363.869987150706, 2413.8431001984814, 2436.1229957215387, 2709.875401708289, 11905.28568285897, 14016.789748153786, 14245.287735613281, 14374.856251437457, 14467.059641123293, 1695.7286834106483, 1751.509577318353, 1770.672425601255, 1776.5203395083383, 1799.631075629493, 99927.0, 101553.96892062118, 104639.47680261549, 109450.45274773655, 111054.0, 9853.605855765833, 11269.380184089954, 11347.0, 11570.958613724804, 11573.710657233469, 14171.149731016172, 14383.640408989859, 14472.986890917746, 14511.346989385504, 14518.709625807425, 7257.52826066304, 8450.746477605682, 8486.278666313432, 8612.526311052952, 8673.479591224546, 8585.18440748128, 8602.698905538276, 8623.568821558896, 8667.653293868301, 8676.956615216883, 3612.5664920209742, 4491.214037543416, 4524.23088075028, 4526.049529598756, 4565.20108980927, 1960.8235258826774, 2001.2295266322571, 2018.1826360324092, 2026.7770545240012, 2052.5073982244153, 877.3463769491769, 942.505184777997, 1006.4313662780505, 1021.5556233038652, 1073.9624113156, 18586.0, 18799.718004229948, 18898.244070237037, 18962.62074758497, 19393.90303048504, 918.4672889724078, 936.2089034765612, 939.3424602778083, 993.9950300248453, 998.6404894238068, 8298.875516867252, 10100.74748131288, 10159.018991296627, 10216.651763636339, 10283.125934295518, 600351.0, 603482.0, 613729.0, 618470.0, 629709.0, 96228.0, 112401.0, 120476.0, 121082.0, 124282.0, 965.6813251627086, 975.5171190260867, 996.7259003773875, 998.2413365841867, 1004.7940172264668, 16472.42346517866, 16562.83437165613, 16805.32778688839, 17069.40257090996, 17074.48776536706, 48897.0, 49243.0, 50340.993180136924, 50350.74824625853, 51275.1076486553, 66008.0, 66151.66924165348, 66193.0, 69068.0, 69874.60250795058, 7558.546487210785, 7729.961350193213, 7812.92187078122, 7831.608419579112, 8124.796880078045, 8670.78323041929, 9111.587703905905, 9830.803383932138, 12075.27548347102, 12096.879031209928, 10030.398176109456, 10257.0, 10401.167906567305, 10448.007439293255, 10559.577616895238, 9948.651797187063, 10042.347247428894, 10229.74425639365, 10305.278630495794, 10429.322094063862, 7325.523840950321, 8822.514761687911, 9759.902400976183, 10323.587056517656, 10559.841602375816, 7551.811204719706, 7884.172161922916, 7950.085740139958, 8008.6396112173925, 8254.669813207405, 10295.897041029493, 10546.103581195684, 10612.8938710615, 10805.837912431478, 10910.399928004035], "value": 9190.563987075306, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1581.9446319378824, 1587.6665900160963, 1612.9677406451872, 1934.7001214811692, 1951.0537389366164, 8109.553974531398, 8563.571821408928, 8568.742937711879, 8858.424202426846, 8899.599518021689, 7972.923655306532, 8651.307895368356, 8663.047064822864, 8700.216980471756, 8724.738257852274, 2157.611629906612, 2365.550545396378, 2371.845830021052, 2372.78644921957, 2379.0245999140357, 2367.6803631509783, 2367.869767162805, 2416.5891798394314, 2419.891104900275, 2424.200013995386, 2344.906203751848, 2363.3973336799177, 2369.8815059247054, 2402.771736685012, 2409.590369637157, 2266.9433264168365, 2334.556434277482, 2337.8129749620075, 2353.1528649686097, 2360.3509035015336, 2057.77364489906, 2367.727711313187, 2395.173665085543, 2406.2901444073864, 2407.7712617301327, 8848.159424854628, 8915.598798054103, 8921.152490513456, 8954.328425368058, 8957.552122393854, 5044.218146187347, 5180.740962951875, 5248.448912864133, 5483.725813709339, 5522.337319521643, 3412.9488057678905, 3467.115885449194, 3546.343926373603, 3648.963510364889, 3654.031681604377, 1025.072309559842, 1049.6221360310283, 1053.0890779475699, 1056.7410984308895, 1081.7566047639298, 11652.242604230783, 11712.53149874004, 11825.231359961661, 11870.109741769316, 11917.0, 3910.4720862683744, 4546.227141385973, 4691.225947718656, 4707.8587642370785, 4758.809647614126, 9231.215346695471, 9330.58012389444, 9358.204552612968, 9363.90636093644, 9382.296327775444, 5342.679439233659, 5402.459754024566, 5410.161424979135, 5414.052540805344, 5438.456154384609, 3729.533808273966, 4438.511763705989, 4983.202687569971, 5008.023435430056, 5009.248612708123, 3715.3126671565847, 3807.2766174427325, 3822.57951625321, 3842.193139440693, 3906.5898080701686, 1163.4182908545727, 1212.1878341511208, 1222.9205101668363, 1234.7530493901077, 1239.3617287097152, 777.3780975219802, 814.5967745965667, 897.5512243878061, 914.8993610702818, 950.8526178442353, 5552.666839989613, 5777.075667893117, 5914.4381283777975, 5941.494972927349, 6017.0, 459.6690382924289, 461.266586128058, 473.3042427631376, 478.1345764166869, 480.69475882814663, 3762.981185094057, 3797.468354430382, 3810.8094595270127, 3838.692904567628, 3907.9804600976813, 8760.386772925835, 10400.47997600117, 10509.474526273656, 10855.294405863593, 10918.563257469612, 10557.894421055844, 10690.78618427627, 10694.572217111385, 10789.730256743644, 10860.619878304222, 7858.410619203582, 8140.145284745135, 8307.750767477106, 8349.958250208832, 8367.079621241659, 5675.772969081356, 5733.569982251264, 5756.798512052058, 5794.739236734273, 5824.126381042877, 9947.801043978936, 10020.248481364073, 10118.949405252926, 10183.032611901857, 10183.69448916504, 9621.711348659552, 9832.213422926006, 10257.33327333721, 10282.971702829656, 10309.690709278733, 7009.12385951756, 7443.627818609156, 7471.701131954878, 7593.392528597809, 7609.124950630749, 8499.320054395757, 8534.018587862476, 8578.270846978077, 8597.054324024228, 8600.967883853977, 7416.8887466686965, 7551.886721699069, 7603.0876294844975, 7618.085829700472, 7620.657070431955, 7345.70617175307, 7393.297636724503, 7426.4430167736555, 7507.88738168917, 7578.507397019176, 5915.378885217162, 7104.3961263293195, 7139.071920650283, 7141.64291785419, 7187.425005999403, 4812.5668689818085, 4928.014397120662, 5018.347614810052, 5020.3724534433195, 5100.897982040413, 4946.678465899705, 5062.569681577107, 5062.924056139087, 5084.466131056186, 5342.572594192533], "value": 4482.6236919416515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.88964414234306, 313.30915331693603, 330.81143748063596, 331.32409883836976, 331.84403330434685, 4296.441462609856, 4768.284757286405, 5298.417174110845, 5374.731263436827, 5510.311211098613, 4750.263709125091, 4873.220284754431, 4883.218685010399, 5086.6439349245475, 5450.836474905749, 3575.982120089409, 3723.1622884850963, 4120.361343991679, 4151.667866570682, 4153.626173644371, 3473.513708080871, 3698.2418604186078, 3872.283627528916, 3988.24223397555, 4124.010237542998, 3575.998720358291, 3689.446583012556, 3749.343864823645, 4098.180363927211, 4100.343944968806, 3198.984005079983, 4070.470838790953, 4119.134981653856, 4121.340585506305, 4122.587741225853, 3565.893023209308, 3576.159602493412, 3578.35589593873, 3585.64143585642, 3586.444101164324, 4591.471980722226, 4912.606991440676, 5030.57240134589, 5217.42608313089, 5263.131588286142, 4666.6500012498855, 4666.859994200179, 4682.29765535169, 4722.488803582854, 4741.170295198326, 5733.88532229353, 5739.110437882135, 5757.286550175156, 5757.41671040463, 5763.942360576382, 3600.5859326177565, 3749.15643980105, 3791.829367678461, 3905.7265991380614, 3912.902177445558, 5801.3038435388025, 5802.854928626776, 5911.586188966774, 6029.125776762345, 6035.336113027563, 3538.885251145881, 3949.308870947573, 3953.802309884494, 3964.9953546917245, 3994.960050399517, 3068.3709839482854, 3779.829907654162, 4001.3997900314603, 4002.799860006988, 4015.578364271768, 4662.0, 4662.836800711959, 4669.571822652202, 4671.15919134559, 4709.740964246931, 5057.21613149955, 5058.9494105058475, 5753.827385178452, 5772.797952071657, 5807.82576522705, 1206.879312068786, 1245.3897590180927, 1377.5109836008273, 1470.6029372069695, 1483.7551803952226, 813.7680760983092, 954.1317401164906, 963.816874793787, 977.3940157102642, 994.0208894239169, 4598.471175814757, 5773.566982476331, 5812.38969908162, 5816.651000939873, 5849.883002340014, 5715.828525144252, 6036.913355595947, 6363.7772677956045, 6408.1349017881985, 6418.486521078303, 4385.451818522684, 4855.223164293767, 5056.82301119459, 5100.081985242692, 5509.47659972302, 2807.6490438695164, 3292.2921571862166, 3391.236971681352, 3433.6051354094097, 3472.965270347315, 6396.68867882083, 6500.252470965899, 6790.898136527857, 6808.863822723518, 6811.523193376416, 5311.760970756325, 5548.334199896039, 6562.704678289489, 6671.933280667228, 6675.766348177791, 6041.7916416716835, 6358.904616430757, 6605.900911486331, 6733.6296503692765, 6765.932340676629, 5653.830385088454, 5910.763569457174, 6482.448991835558, 6490.253620833664, 6506.869862602816, 5295.405251426851, 6269.3417191196095, 6376.330485299162, 6388.680565971776, 6394.072859435449, 5832.850260859089, 6274.937250627435, 6445.0, 6482.967585162044, 6786.151731033621, 3378.35811195872, 4152.231837110145, 4158.69574989884, 4210.368444733192, 4212.1575684863765, 5344.625876188629, 5604.607677462538, 6468.482521398183, 6522.478201743944, 6563.523207278324, 5014.693241108985, 5237.188235823453, 5591.720413979366, 5696.177223288508, 5790.420957904176, 4700.318453824245, 5710.401087695452, 5725.0248664210785, 5754.769809207585, 5757.395473475225, 4510.458744950627, 5232.415795180314, 5351.812686555952, 5577.051901176891, 5613.214150019082, 5018.548330650259, 5465.059903734117, 5606.467385598362, 5704.229928959538, 5710.8572285691525, 4729.952700472952, 5315.867103322448, 5581.358143813513, 5613.382527921844, 5629.789595237044], "value": 4244.71268296541, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.2981490545496, 660.9074729537864, 753.5629334985707, 765.3571000359697, 770.1451388958255, 14635.414583416665, 14638.414463421477, 14772.0, 14829.777553336695, 15175.08949463033, 9875.308728389016, 13533.458661653547, 13591.456341746318, 13653.317334133304, 14287.785538229236, 742.6546655805049, 897.9102089791002, 913.2146354135439, 932.8181004704086, 952.3190918493276, 803.586153131138, 924.2559739409772, 943.2077055273569, 948.2177203806853, 950.1211379473993, 990.2078337330144, 991.1080027974808, 991.1476130527741, 991.6479649724353, 992.8411454167334, 916.9174774270315, 955.9713208603753, 963.3593660215948, 981.7643765496301, 984.4142735072656, 707.2609123465991, 721.5237942957675, 733.6185183704497, 734.071399679406, 735.5219107580116, 14625.195614241213, 14781.556553303419, 14811.851881481154, 14862.182579958098, 14905.552833415015, 12192.695182620417, 12370.690732731753, 12390.194637348544, 12439.75120497594, 12653.36733163338, 9754.115420065193, 9860.211183105333, 10098.596056157745, 10103.19174466041, 10403.947980260125, 3446.758726889119, 3476.66971637694, 3488.4244099723514, 3523.119220194951, 3587.515685382466, 74533.0, 75112.87330690044, 76140.23859761388, 76514.0, 79201.81197282045, 9191.724248272563, 9194.724158275263, 9322.0, 9588.856167157499, 9644.90355096454, 21475.03362348698, 21523.67714484284, 21553.245636402964, 21566.56866862654, 21660.566788664455, 7207.243239459886, 7229.8554028919125, 7329.560226386434, 7377.557346559116, 7618.047744031996, 3824.2160357126722, 3904.648581627667, 3950.111224974415, 3994.6205110514456, 4216.945763559111, 3508.9648553676725, 3511.877084302037, 3593.299306635181, 3594.802285874302, 3599.820008999592, 557.8075563930438, 628.8710814283061, 636.9426751592379, 648.2771709543824, 659.4724220623577, 565.9009673307237, 568.366271607155, 584.2967749011584, 589.4223660812428, 602.1660000898787, 7765.7281995131025, 7829.060512738508, 7894.0921793993275, 7966.561839098789, 8162.795930101676, 509.8266589359561, 568.6275489554348, 590.4066413254737, 592.3484167415894, 595.1429940885112, 3821.0, 3830.9616903830606, 3836.9808150959066, 3841.3277676407074, 3873.283442563135, 90887.54556227177, 91357.0, 92088.61867072, 92312.0, 95294.52352738331, 71937.0, 74649.62675186589, 75015.62492187505, 77093.0, 77611.61194194105, 67633.66183169052, 67702.66148669225, 67922.99999999901, 68172.0, 72001.91997120048, 6352.9047064293145, 7656.961715191388, 7669.118051423934, 7697.230276972258, 7826.41301902371, 29693.85153074221, 44459.55540444554, 45559.31661025154, 45695.77152114218, 45890.770546147054, 64456.67771661111, 64890.67554662196, 65421.67289163524, 65637.01544476741, 65772.67113664401, 10446.52990615409, 10600.416977066336, 10972.615958441736, 11080.5567777288, 11358.0, 11744.354060526412, 12266.405367302194, 12329.815052774395, 12366.258024518198, 12611.243325400505, 10520.842187367349, 11095.500702468247, 11375.488103035548, 11582.826257605973, 11828.784441490512, 11267.943660281973, 11293.096552275634, 11383.772324553629, 11460.197786155222, 12104.092193085378, 9556.61773529051, 11347.14896382791, 11546.76906461883, 11703.76592468163, 11835.349055802013, 8934.955325223333, 9417.717468475958, 9450.007749186236, 9863.358881672668, 10136.44249566281, 9594.280428967715, 9689.176420004096, 9756.121844493271, 9859.915409304827, 9988.850167247352], "value": 6795.994612189056, "unit": "score"}], "name": "dromaeo_css", "value": 7009.364355431979, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 121.0, 173.0, 177.0, 121.0, 124.0, 120.0, 117.0, 114.0, 114.0], "value": 129.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 125.0, 187.0, 198.0, 154.0, 143.0, 120.0, 160.0, 161.0, 124.0], "value": 151.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 261.0, 273.0, 217.0, 194.0, 199.0, 197.0, 229.0, 177.0, 173.0], "value": 212.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 105.0, 115.0, 175.0, 58.0, 57.0, 55.0, 58.0, 126.0, 57.0], "value": 87.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 97.0, 127.0, 77.0, 80.0, 81.0, 118.0, 82.0, 81.0, 119.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 131.0, 89.0, 86.0, 89.0, 85.0, 105.0, 132.0, 108.0, 87.0], "value": 103.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 104.0, 152.0, 110.0, 142.0, 94.0, 155.0, 154.0, 120.0, 95.0], "value": 125.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 92.0, 92.0, 93.0, 93.0, 89.0, 95.0, 91.0, 91.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 62.0, 61.0, 61.0, 62.0, 60.0, 76.0, 63.0, 61.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 68.0, 66.0, 75.0, 71.0, 65.0, 67.0, 69.0, 69.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 82.0, 85.0, 82.0, 85.0, 82.0, 86.0, 82.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 167.0, 152.0, 138.0, 150.0, 143.0, 162.0, 167.0, 195.0, 147.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 246.0, 275.0, 213.0, 199.0, 246.0, 272.0, 221.0, 199.0, 200.0], "value": 227.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 78.0, 100.0, 61.0, 63.0, 99.0, 100.0, 96.0, 85.0], "value": 82.0, "unit": "score"}], "name": "kraken", "value": 1676.3000000000002, "alertThreshold": 2.0}]} 05:37:48 INFO - Return code: 0 05:37:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:37:48 ERROR - # TBPL SUCCESS # 05:37:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:48 INFO - 2016-04-28 05:37:48.889 screenresolution[1696:10513] starting screenresolution argv=screenresolution get 05:37:48 INFO - 2016-04-28 05:37:48.892 screenresolution[1696:10513] Display 0: 1600x1200x32@60 05:37:48 INFO - 2016-04-28 05:37:48.898 screenresolution[1697:10516] starting screenresolution argv=screenresolution list 05:37:48 INFO - Available Modes on Display 0 05:37:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:49 INFO - Intel Iris: 05:37:49 INFO - Chipset Model: Intel Iris 05:37:49 INFO - Type: GPU 05:37:49 INFO - Bus: Built-In 05:37:49 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:49 INFO - Vendor: Intel (0x8086) 05:37:49 INFO - Device ID: 0x0a2e 05:37:49 INFO - Revision ID: 0x0009 05:37:49 INFO - Displays: 05:37:49 INFO - Display: 05:37:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:49 INFO - Display Serial Number: 1600x1200 60 05:37:49 INFO - Main Display: Yes 05:37:49 INFO - Mirror: Off 05:37:49 INFO - Online: Yes 05:37:49 INFO - Rotation: Supported 05:37:49 INFO - Return code: 0 05:37:49 INFO - Running post-action listener: _resource_record_post_action 05:37:49 INFO - Running post-run listener: _resource_record_post_run 05:37:50 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32773632; Write bytes: 697880576; Read time: 334; Write time: 2141 05:37:50 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393353728; Write bytes: 387692544; Read time: 29969; Write time: 750 05:37:50 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 30967296; Write bytes: 298776576; Read time: 312; Write time: 1364 05:37:50 INFO - Running post-run listener: _upload_blobber_files 05:37:50 INFO - Blob upload gear active. 05:37:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:37: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 05:37: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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:37: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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:37:50 INFO - (blobuploader) - INFO - Open directory for files ... 05:37:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:37:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:37:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:51 INFO - (blobuploader) - INFO - Done attempting. 05:37:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:37:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:37:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:52 INFO - (blobuploader) - INFO - Done attempting. 05:37:52 INFO - (blobuploader) - INFO - Iteration through files over. 05:37:52 INFO - Return code: 0 05:37:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:37:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:37:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/752d8ef50de0c14eec7a320178257efd9af33406c1a4b637b340052e517609f984790ebc41580c9966beb05a18b7a8c0ca9cf322848443171404ee2e91f1656d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8dc25d2a4fa0093e2dbe27b03a2aab2451796d82fc2b3cffd1d3b0e2ac58fd862b131b061699f09e9e6ea68f9daa30290580964260a8ce90ef0a8752e0cea96"} 05:37:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:37:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:37:52 INFO - Contents: 05:37:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/752d8ef50de0c14eec7a320178257efd9af33406c1a4b637b340052e517609f984790ebc41580c9966beb05a18b7a8c0ca9cf322848443171404ee2e91f1656d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8dc25d2a4fa0093e2dbe27b03a2aab2451796d82fc2b3cffd1d3b0e2ac58fd862b131b061699f09e9e6ea68f9daa30290580964260a8ce90ef0a8752e0cea96"} 05:37:52 INFO - Running post-run listener: copy_logs_to_upload_dir 05:37:52 INFO - Copying logs to upload dir... 05:37:52 INFO - mkdir: /builds/slave/test/build/upload/logs 05:37:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1370.926672 ========= master_lag: 0.46 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 51 secs) (at 2016-04-28 05:37:52.693834) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 05:37:52.699065) ========= 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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/752d8ef50de0c14eec7a320178257efd9af33406c1a4b637b340052e517609f984790ebc41580c9966beb05a18b7a8c0ca9cf322848443171404ee2e91f1656d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8dc25d2a4fa0093e2dbe27b03a2aab2451796d82fc2b3cffd1d3b0e2ac58fd862b131b061699f09e9e6ea68f9daa30290580964260a8ce90ef0a8752e0cea96"} build_url:https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011223 build_url: 'https://queue.taskcluster.net/v1/task/Wo_MdS1xRd-vp2E3-Zi7pg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/752d8ef50de0c14eec7a320178257efd9af33406c1a4b637b340052e517609f984790ebc41580c9966beb05a18b7a8c0ca9cf322848443171404ee2e91f1656d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8dc25d2a4fa0093e2dbe27b03a2aab2451796d82fc2b3cffd1d3b0e2ac58fd862b131b061699f09e9e6ea68f9daa30290580964260a8ce90ef0a8752e0cea96"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 05:37:52.735371) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 05:37:52.735800) ========= 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.3KeW5BUsGD/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.jN5nBdT3Gm/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.005160 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 05:37:52.788137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 05:37:52.788497) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 05:37:52.788883) ========= ========= Total master_lag: 0.68 =========