builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0078 starttime: 1460886441.32 results: success (0) buildid: 20160417004052 builduid: 335ad8fefb6d4ce6b28cd91821b98e02 revision: 02b8dc35ec80a95dd4f1cdd5a4075718aed233f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:21.317150) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:21.317650) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:21.317979) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:21.453575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:21.454000) ========= 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.glFmtWhVtG/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.gC7MgLOBNC/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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-17 02:47:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.52M=0.002s 2016-04-17 02:47:22 (7.52 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.581157 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:22.075627) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:22.076027) ========= 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.glFmtWhVtG/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.gC7MgLOBNC/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.019502 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:22.146214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-17 02:47:22.146921) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 02b8dc35ec80a95dd4f1cdd5a4075718aed233f2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 02b8dc35ec80a95dd4f1cdd5a4075718aed233f2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.glFmtWhVtG/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.gC7MgLOBNC/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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-17 02:47:22,215 truncating revision to first 12 chars 2016-04-17 02:47:22,215 Setting DEBUG logging. 2016-04-17 02:47:22,215 attempt 1/10 2016-04-17 02:47:22,215 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/02b8dc35ec80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-17 02:47:23,426 unpacking tar archive at: mozilla-aurora-02b8dc35ec80/testing/mozharness/ program finished with exit code 0 elapsedTime=1.457494 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-17 02:47:23.673120) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:23.673410) ========= script_repo_revision: 02b8dc35ec80a95dd4f1cdd5a4075718aed233f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:23.673830) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:23.674113) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-17 02:47:23.826762) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-04-17 02:47:23.827049) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.glFmtWhVtG/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.gC7MgLOBNC/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:47:24 INFO - MultiFileLogger online at 20160417 02:47:24 in /builds/slave/test 02:47:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:47:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:47:24 INFO - {'append_to_log': False, 02:47:24 INFO - 'base_work_dir': '/builds/slave/test', 02:47:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:47:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:47:24 INFO - 'branch': 'Mozilla-Aurora', 02:47:24 INFO - 'buildbot_json_path': 'buildprops.json', 02:47:24 INFO - 'config_files': ('talos/mac_config.py',), 02:47:24 INFO - 'default_actions': ('clobber', 02:47:24 INFO - 'read-buildbot-config', 02:47:24 INFO - 'download-and-extract', 02:47:24 INFO - 'populate-webroot', 02:47:24 INFO - 'create-virtualenv', 02:47:24 INFO - 'install', 02:47:24 INFO - 'run-tests'), 02:47:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:47:24 INFO - 'download_minidump_stackwalk': True, 02:47:24 INFO - 'download_symbols': 'ondemand', 02:47:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:47:24 INFO - 'tooltool.py': '/tools/tooltool.py', 02:47:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:47:24 INFO - '/tools/misc-python/virtualenv.py')}, 02:47:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:47:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:47:24 INFO - 'installer_path': 'installer.exe', 02:47:24 INFO - 'log_level': 'info', 02:47:24 INFO - 'log_name': 'talos', 02:47:24 INFO - 'log_to_console': True, 02:47:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:47:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:47:24 INFO - 'opt_config_files': (), 02:47:24 INFO - 'pip_index': False, 02:47:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:47:24 INFO - 'cmd': ('bash', 02:47:24 INFO - '-c', 02:47:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:47:24 INFO - 'enabled': True, 02:47:24 INFO - 'halt_on_failure': False, 02:47:24 INFO - 'name': 'check_screen_resolution'},), 02:47:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:47:24 INFO - 'cmd': ('bash', 02:47:24 INFO - '-c', 02:47:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:47:24 INFO - 'enabled': True, 02:47:24 INFO - 'halt_on_failure': False, 02:47:24 INFO - 'name': 'check_screen_resolution'},), 02:47:24 INFO - 'run_cmd_checks_enabled': True, 02:47:24 INFO - 'sps_profile': False, 02:47:24 INFO - 'sps_profile_interval': 0, 02:47:24 INFO - 'suite': 'dromaeojs-e10s', 02:47:24 INFO - 'system_bits': '32', 02:47:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:47:24 INFO - 'title': 't-yosemite-r7-0078', 02:47:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:47:24 INFO - 'use_talos_json': True, 02:47:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:47:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:47:24 INFO - 'work_dir': 'build'} 02:47:24 INFO - ##### 02:47:24 INFO - ##### Running clobber step. 02:47:24 INFO - ##### 02:47:24 INFO - Running pre-action listener: _resource_record_pre_action 02:47:24 INFO - Running main action method: clobber 02:47:24 INFO - rmtree: /builds/slave/test/build 02:47:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:47:24 INFO - Running post-action listener: _resource_record_post_action 02:47:24 INFO - ##### 02:47:24 INFO - ##### Running read-buildbot-config step. 02:47:24 INFO - ##### 02:47:24 INFO - Running pre-action listener: _resource_record_pre_action 02:47:24 INFO - Running main action method: read_buildbot_config 02:47:24 INFO - Using buildbot properties: 02:47:24 INFO - { 02:47:24 INFO - "project": "", 02:47:24 INFO - "product": "firefox", 02:47:24 INFO - "script_repo_revision": "production", 02:47:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:47:24 INFO - "repository": "", 02:47:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:47:24 INFO - "buildid": "20160417004052", 02:47:24 INFO - "stage_platform": "macosx64", 02:47:24 INFO - "basedir": "/builds/slave/test", 02:47:24 INFO - "nightly_build": "True", 02:47:24 INFO - "buildnumber": 271, 02:47:24 INFO - "slavename": "t-yosemite-r7-0078", 02:47:24 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:47:24 INFO - "platform": "yosemite_r7", 02:47:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:47:24 INFO - "branch": "mozilla-aurora", 02:47:24 INFO - "repo_path": "releases/mozilla-aurora", 02:47:24 INFO - "revision": "02b8dc35ec80a95dd4f1cdd5a4075718aed233f2", 02:47:24 INFO - "pgo_build": "False", 02:47:24 INFO - "builduid": "335ad8fefb6d4ce6b28cd91821b98e02", 02:47:24 INFO - "slavebuilddir": "test" 02:47:24 INFO - } 02:47:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 02:47:24 INFO - Running post-action listener: _resource_record_post_action 02:47:24 INFO - ##### 02:47:24 INFO - ##### Running download-and-extract step. 02:47:24 INFO - ##### 02:47:24 INFO - Running pre-action listener: _resource_record_pre_action 02:47:24 INFO - Running main action method: download_and_extract 02:47:24 INFO - mkdir: /builds/slave/test/build/tests 02:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:24 INFO - https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:47:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 02:47:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 02:47:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 02:47:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 02:47:51 INFO - Downloaded 1277 bytes. 02:47:51 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 02:47:51 INFO - Using the following test package requirements: 02:47:51 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 02:47:51 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:47:51 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 02:47:51 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:47:51 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 02:47:51 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:47:51 INFO - u'jsshell-mac.zip'], 02:47:51 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:47:51 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 02:47:51 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 02:47:51 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:47:51 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 02:47:51 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:47:51 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 02:47:51 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:47:51 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 02:47:51 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 02:47:51 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:47:51 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 02:47:51 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:47:51 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')]} 02:47:51 INFO - https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:47:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:47:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:47:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:47:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:52 INFO - Downloaded 17911131 bytes. 02:47:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:47:55 INFO - Return code: 0 02:47:55 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:47:55 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')]} 02:47:55 INFO - https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:47:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:47:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:47:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:57 INFO - Downloaded 17911131 bytes. 02:47:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:48:01 INFO - Return code: 0 02:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:01 INFO - https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:48:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 02:48:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 02:48:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 02:48:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:48:02 INFO - Downloaded 10845168 bytes. 02:48:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:48:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:48:02 INFO - Return code: 0 02:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:02 INFO - https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:48:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 02:48:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 02:48:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 02:48:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 02:48:07 INFO - Downloaded 115358507 bytes. 02:48:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 02:48:07 INFO - mkdir: /builds/slave/test/properties 02:48:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:48:07 INFO - Writing to file /builds/slave/test/properties/build_url 02:48:07 INFO - Contents: 02:48:07 INFO - build_url:https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 02:48:09 INFO - Running post-action listener: _resource_record_post_action 02:48:09 INFO - Running post-action listener: set_extra_try_arguments 02:48:09 INFO - ##### 02:48:09 INFO - ##### Running populate-webroot step. 02:48:09 INFO - ##### 02:48:09 INFO - Running pre-action listener: _resource_record_pre_action 02:48:09 INFO - Running main action method: populate_webroot 02:48:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:48:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:48:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:48:09 INFO - '--tppagecycles', 02:48:09 INFO - '7'], 02:48:09 INFO - 'tests': ['tsvgm']}}, 02:48:09 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 02:48:09 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:48:09 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 02:48:09 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:48:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:48:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:09 INFO - 'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:48:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:09 INFO - 'tests': ['damp', 'tps']}, 02:48:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:09 INFO - 'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['damp', 'tps']}, 02:48:09 INFO - 'g3': {'tests': ['dromaeo_dom']}, 02:48:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:48:09 INFO - 'other': {'tests': ['a11yr', 02:48:09 INFO - 'ts_paint', 02:48:09 INFO - 'tpaint', 02:48:09 INFO - 'sessionrestore', 02:48:09 INFO - 'sessionrestore_no_auto_restore', 02:48:09 INFO - 'tabpaint']}, 02:48:09 INFO - 'other-e10s': {'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['a11yr', 02:48:09 INFO - 'ts_paint', 02:48:09 INFO - 'tpaint', 02:48:09 INFO - 'sessionrestore', 02:48:09 INFO - 'sessionrestore_no_auto_restore', 02:48:09 INFO - 'tabpaint']}, 02:48:09 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['a11yr', 02:48:09 INFO - 'ts_paint', 02:48:09 INFO - 'tpaint', 02:48:09 INFO - 'sessionrestore', 02:48:09 INFO - 'sessionrestore_no_auto_restore', 02:48:09 INFO - 'tabpaint']}, 02:48:09 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['a11yr', 02:48:09 INFO - 'ts_paint', 02:48:09 INFO - 'tpaint', 02:48:09 INFO - 'sessionrestore', 02:48:09 INFO - 'sessionrestore_no_auto_restore', 02:48:09 INFO - 'tabpaint']}, 02:48:09 INFO - 'other_l64': {'tests': ['a11yr', 02:48:09 INFO - 'ts_paint', 02:48:09 INFO - 'tpaint', 02:48:09 INFO - 'sessionrestore', 02:48:09 INFO - 'sessionrestore_no_auto_restore', 02:48:09 INFO - 'tabpaint']}, 02:48:09 INFO - 'other_nol64': {'tests': ['a11yr', 02:48:09 INFO - 'ts_paint', 02:48:09 INFO - 'tpaint', 02:48:09 INFO - 'sessionrestore', 02:48:09 INFO - 'sessionrestore_no_auto_restore', 02:48:09 INFO - 'tabpaint']}, 02:48:09 INFO - 'svgr': {'tests': ['tsvgx', 02:48:09 INFO - 'tsvgr_opacity', 02:48:09 INFO - 'tart', 02:48:09 INFO - 'tscrollx', 02:48:09 INFO - 'cart']}, 02:48:09 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['tsvgx', 02:48:09 INFO - 'tsvgr_opacity', 02:48:09 INFO - 'tart', 02:48:09 INFO - 'tscrollx', 02:48:09 INFO - 'cart']}, 02:48:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:09 INFO - 'tests': ['tp5o']}, 02:48:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:09 INFO - 'talos_options': ['--e10s'], 02:48:09 INFO - 'tests': ['tp5o']}, 02:48:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:09 INFO - 'talos_options': ['--xperf_path', 02:48:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:48:09 INFO - 'tests': ['tp5n']}, 02:48:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:09 INFO - 'talos_options': ['--e10s', 02:48:09 INFO - '--xperf_path', 02:48:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:48:09 INFO - 'tests': ['tp5n']}}, 02:48:09 INFO - 'talos.zip': {'path': '', 02:48:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:48:09 INFO - Running post-action listener: _resource_record_post_action 02:48:09 INFO - ##### 02:48:09 INFO - ##### Running create-virtualenv step. 02:48:09 INFO - ##### 02:48:09 INFO - Running pre-action listener: _resource_record_pre_action 02:48:09 INFO - Running main action method: create_virtualenv 02:48:09 INFO - Creating virtualenv /builds/slave/test/build/venv 02:48:09 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 02:48:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:48:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:48:09 INFO - Using real prefix '/tools/python27' 02:48:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:48:09 INFO - Installing distribute.............................................................................................................................................................................................done. 02:48:12 INFO - Installing pip.................done. 02:48:12 INFO - Return code: 0 02:48:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:48: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')]} 02:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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')]} 02:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:12 INFO - 'HOME': '/Users/cltbld', 02:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:12 INFO - 'LOGNAME': 'cltbld', 02:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:12 INFO - 'MOZ_NO_REMOTE': '1', 02:48:12 INFO - 'NO_EM_RESTART': '1', 02:48:12 INFO - 'PAGER': '/bin/cat', 02:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:12 INFO - 'PWD': '/builds/slave/test', 02:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:12 INFO - 'SHELL': '/bin/bash', 02:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:12 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:12 INFO - 'USER': 'cltbld', 02:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:12 INFO - 'XPC_FLAGS': '0x0', 02:48:12 INFO - 'XPC_SERVICE_NAME': '0', 02:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:13 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 02:48:13 INFO - Cleaning up... 02:48:13 INFO - Return code: 0 02:48:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:48: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')]} 02:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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')]} 02:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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', '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 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48: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', 'psutil>=0.7.1'] in /builds/slave/test/build 02:48: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 psutil>=0.7.1 02:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:13 INFO - 'HOME': '/Users/cltbld', 02:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:13 INFO - 'LOGNAME': 'cltbld', 02:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:13 INFO - 'MOZ_NO_REMOTE': '1', 02:48:13 INFO - 'NO_EM_RESTART': '1', 02:48:13 INFO - 'PAGER': '/bin/cat', 02:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:13 INFO - 'PWD': '/builds/slave/test', 02:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:13 INFO - 'SHELL': '/bin/bash', 02:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:13 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:13 INFO - 'USER': 'cltbld', 02:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:13 INFO - 'XPC_FLAGS': '0x0', 02:48:13 INFO - 'XPC_SERVICE_NAME': '0', 02:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:13 INFO - Downloading/unpacking psutil>=0.7.1 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:48:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:16 INFO - Installing collected packages: psutil 02:48:16 INFO - Running setup.py install for psutil 02:48:16 INFO - building 'psutil._psutil_osx' extension 02:48:16 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 02:48:16 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 02:48:16 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 02:48:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:48:16 INFO - building 'psutil._psutil_posix' extension 02:48:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:48:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:48:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:48:16 INFO - ^ 02:48:16 INFO - 1 warning generated. 02:48:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:17 INFO - Successfully installed psutil 02:48:17 INFO - Cleaning up... 02:48:17 INFO - Return code: 0 02:48:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:17 INFO - 'HOME': '/Users/cltbld', 02:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:17 INFO - 'LOGNAME': 'cltbld', 02:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:17 INFO - 'MOZ_NO_REMOTE': '1', 02:48:17 INFO - 'NO_EM_RESTART': '1', 02:48:17 INFO - 'PAGER': '/bin/cat', 02:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:17 INFO - 'PWD': '/builds/slave/test', 02:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:17 INFO - 'SHELL': '/bin/bash', 02:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:17 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:17 INFO - 'USER': 'cltbld', 02:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:17 INFO - 'XPC_FLAGS': '0x0', 02:48:17 INFO - 'XPC_SERVICE_NAME': '0', 02:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:48:19 INFO - Installing collected packages: mozsystemmonitor 02:48:19 INFO - Running setup.py install for mozsystemmonitor 02:48:20 INFO - Successfully installed mozsystemmonitor 02:48:20 INFO - Cleaning up... 02:48:20 INFO - Return code: 0 02:48:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:20 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 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48:20 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 02:48:20 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 02:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:20 INFO - 'HOME': '/Users/cltbld', 02:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:20 INFO - 'LOGNAME': 'cltbld', 02:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:20 INFO - 'MOZ_NO_REMOTE': '1', 02:48:20 INFO - 'NO_EM_RESTART': '1', 02:48:20 INFO - 'PAGER': '/bin/cat', 02:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:20 INFO - 'PWD': '/builds/slave/test', 02:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:20 INFO - 'SHELL': '/bin/bash', 02:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:20 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:20 INFO - 'USER': 'cltbld', 02:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:20 INFO - 'XPC_FLAGS': '0x0', 02:48:20 INFO - 'XPC_SERVICE_NAME': '0', 02:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:20 INFO - Downloading/unpacking blobuploader==1.2.4 02:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:22 INFO - Downloading blobuploader-1.2.4.tar.gz 02:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:48:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:48:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:48:23 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 02:48:23 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 02:48:23 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 02:48:23 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 02:48:23 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 02:48:23 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 02:48:23 INFO - Downloading docopt-0.6.1.tar.gz 02:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:48:24 INFO - Installing collected packages: blobuploader, requests, docopt 02:48:24 INFO - Running setup.py install for blobuploader 02:48:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:48:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:48:24 INFO - Running setup.py install for requests 02:48:24 INFO - Running setup.py install for docopt 02:48:24 INFO - Successfully installed blobuploader requests docopt 02:48:24 INFO - Cleaning up... 02:48:24 INFO - Return code: 0 02:48:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:24 INFO - 'HOME': '/Users/cltbld', 02:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:24 INFO - 'LOGNAME': 'cltbld', 02:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:24 INFO - 'MOZ_NO_REMOTE': '1', 02:48:24 INFO - 'NO_EM_RESTART': '1', 02:48:24 INFO - 'PAGER': '/bin/cat', 02:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:24 INFO - 'PWD': '/builds/slave/test', 02:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:24 INFO - 'SHELL': '/bin/bash', 02:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:24 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:24 INFO - 'USER': 'cltbld', 02:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:24 INFO - 'XPC_FLAGS': '0x0', 02:48:24 INFO - 'XPC_SERVICE_NAME': '0', 02:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-xpf5sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-24YSSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-7qIC3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ltg1iR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-10wUDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-rcAFTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-9_mdyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:25 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-hPfjra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Ref_QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-qK6kWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-69pyet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-YzNOPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-SbvSrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-LbxIUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Le6zit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-TamNJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:26 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-loTjaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:48:26 INFO - Running setup.py install for manifestparser 02:48:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:48:27 INFO - Running setup.py install for mozcrash 02:48:27 INFO - Running setup.py install for mozdebug 02:48:27 INFO - Running setup.py install for mozdevice 02:48:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:48:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:48:27 INFO - Running setup.py install for mozfile 02:48:27 INFO - Running setup.py install for mozhttpd 02:48:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:48:27 INFO - Running setup.py install for mozinfo 02:48:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:48:27 INFO - Running setup.py install for mozInstall 02:48:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Running setup.py install for mozleak 02:48:28 INFO - Running setup.py install for mozlog 02:48:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Running setup.py install for moznetwork 02:48:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Running setup.py install for mozprocess 02:48:28 INFO - Running setup.py install for mozprofile 02:48:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:48:28 INFO - Running setup.py install for mozrunner 02:48:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:48:29 INFO - Running setup.py install for mozscreenshot 02:48:29 INFO - Running setup.py install for moztest 02:48:29 INFO - Running setup.py install for mozversion 02:48:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:48:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:48:29 INFO - Cleaning up... 02:48:29 INFO - Return code: 0 02:48:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:29 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')]} 02:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:29 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')]} 02:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:29 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 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48:29 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 02:48:29 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 02:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:29 INFO - 'HOME': '/Users/cltbld', 02:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:29 INFO - 'LOGNAME': 'cltbld', 02:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:29 INFO - 'MOZ_NO_REMOTE': '1', 02:48:29 INFO - 'NO_EM_RESTART': '1', 02:48:29 INFO - 'PAGER': '/bin/cat', 02:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:29 INFO - 'PWD': '/builds/slave/test', 02:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:29 INFO - 'SHELL': '/bin/bash', 02:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:29 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:29 INFO - 'USER': 'cltbld', 02:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:29 INFO - 'XPC_FLAGS': '0x0', 02:48:29 INFO - 'XPC_SERVICE_NAME': '0', 02:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-0cUp5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:30 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)) 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-IdYS8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:30 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)) 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-woKNY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:30 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)) 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-CQXeU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:30 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)) 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-nFIJ4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:30 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)) 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-8qrSKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:30 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)) 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-E8YyKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-4lKLQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-0R3I0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-W7fHWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-GhyUa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-eRrvUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-uJEZgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-XKmYjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-TdmO7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-4MgwEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:31 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)) 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-xhEcAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:32 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)) 02:48:32 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)) 02:48:32 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)) 02:48:32 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)) 02:48:32 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)) 02:48:32 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)) 02:48:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:34 INFO - Downloading blessings-1.6.tar.gz 02:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:48:34 INFO - Installing collected packages: blessings 02:48:34 INFO - Running setup.py install for blessings 02:48:34 INFO - Successfully installed blessings 02:48:34 INFO - Cleaning up... 02:48:34 INFO - Return code: 0 02:48:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:35 INFO - Reading from file tmpfile_stdout 02:48:35 INFO - Current package versions: 02:48:35 INFO - blessings == 1.6 02:48:35 INFO - blobuploader == 1.2.4 02:48:35 INFO - docopt == 0.6.1 02:48:35 INFO - manifestparser == 1.1 02:48:35 INFO - mozInstall == 1.12 02:48:35 INFO - mozcrash == 0.17 02:48:35 INFO - mozdebug == 0.1 02:48:35 INFO - mozdevice == 0.48 02:48:35 INFO - mozfile == 1.2 02:48:35 INFO - mozhttpd == 0.7 02:48:35 INFO - mozinfo == 0.9 02:48:35 INFO - mozleak == 0.1 02:48:35 INFO - mozlog == 3.1 02:48:35 INFO - moznetwork == 0.27 02:48:35 INFO - mozprocess == 0.22 02:48:35 INFO - mozprofile == 0.28 02:48:35 INFO - mozrunner == 6.11 02:48:35 INFO - mozscreenshot == 0.1 02:48:35 INFO - mozsystemmonitor == 0.0 02:48:35 INFO - moztest == 0.7 02:48:35 INFO - mozversion == 1.4 02:48:35 INFO - psutil == 3.1.1 02:48:35 INFO - requests == 1.2.3 02:48:35 INFO - wsgiref == 0.1.2 02:48:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:35 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')]} 02:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:35 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')]} 02:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:35 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 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48:35 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 02:48:35 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 02:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:35 INFO - 'HOME': '/Users/cltbld', 02:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:35 INFO - 'LOGNAME': 'cltbld', 02:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:35 INFO - 'MOZ_NO_REMOTE': '1', 02:48:35 INFO - 'NO_EM_RESTART': '1', 02:48:35 INFO - 'PAGER': '/bin/cat', 02:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:35 INFO - 'PWD': '/builds/slave/test', 02:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:35 INFO - 'SHELL': '/bin/bash', 02:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:35 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:35 INFO - 'USER': 'cltbld', 02:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:35 INFO - 'XPC_FLAGS': '0x0', 02:48:35 INFO - 'XPC_SERVICE_NAME': '0', 02:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 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)) 02:48:35 INFO - Cleaning up... 02:48:35 INFO - Return code: 0 02:48:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:48:35 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')]} 02:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:35 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')]} 02:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:35 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 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48:35 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 02:48:35 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 02:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:35 INFO - 'HOME': '/Users/cltbld', 02:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:35 INFO - 'LOGNAME': 'cltbld', 02:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:35 INFO - 'MOZ_NO_REMOTE': '1', 02:48:35 INFO - 'NO_EM_RESTART': '1', 02:48:35 INFO - 'PAGER': '/bin/cat', 02:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:35 INFO - 'PWD': '/builds/slave/test', 02:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:35 INFO - 'SHELL': '/bin/bash', 02:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:35 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:35 INFO - 'USER': 'cltbld', 02:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:35 INFO - 'XPC_FLAGS': '0x0', 02:48:35 INFO - 'XPC_SERVICE_NAME': '0', 02:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:35 INFO - Downloading/unpacking jsonschema 02:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:48:37 INFO - Downloading/unpacking functools32 (from jsonschema) 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:38 INFO - Downloading functools32-3.2.3-2.tar.gz 02:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:48:38 INFO - warning: no files found matching '*.txt' 02:48:38 INFO - no previously-included directories found matching 'build' 02:48:38 INFO - no previously-included directories found matching 'dist' 02:48:38 INFO - no previously-included directories found matching '.git*' 02:48:38 INFO - Installing collected packages: jsonschema, functools32 02:48:38 INFO - Running setup.py install for functools32 02:48:38 INFO - warning: no files found matching '*.txt' 02:48:38 INFO - no previously-included directories found matching 'build' 02:48:38 INFO - no previously-included directories found matching 'dist' 02:48:38 INFO - no previously-included directories found matching '.git*' 02:48:38 INFO - Successfully installed jsonschema functools32 02:48:38 INFO - Cleaning up... 02:48:38 INFO - Return code: 0 02:48:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fcffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95794a00e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, '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.glFmtWhVtG/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.gC7MgLOBNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 02:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:48:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:48:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:38 INFO - 'HOME': '/Users/cltbld', 02:48:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:38 INFO - 'LOGNAME': 'cltbld', 02:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:38 INFO - 'MOZ_NO_REMOTE': '1', 02:48:38 INFO - 'NO_EM_RESTART': '1', 02:48:38 INFO - 'PAGER': '/bin/cat', 02:48:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:38 INFO - 'PWD': '/builds/slave/test', 02:48:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:38 INFO - 'SHELL': '/bin/bash', 02:48:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:48:38 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:48:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:38 INFO - 'USER': 'cltbld', 02:48:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:38 INFO - 'XPC_FLAGS': '0x0', 02:48:38 INFO - 'XPC_SERVICE_NAME': '0', 02:48:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:39 INFO - Downloading/unpacking flake8 02:48:39 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 02:48:39 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 02:48:39 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 02:48:39 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 02:48:39 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 02:48:39 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 02:48:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:48:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:48:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:48:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:48:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:48:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:48:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:48:42 INFO - no previously-included directories found matching 'docs/_build' 02:48:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:48:42 INFO - Running setup.py install for pep8 02:48:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:48:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:48:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:48:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:48:42 INFO - no previously-included directories found matching 'docs/_build' 02:48:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:48:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:48:42 INFO - Cleaning up... 02:48:43 INFO - Return code: 0 02:48:43 INFO - Running post-action listener: _resource_record_post_action 02:48:43 INFO - Running post-action listener: _start_resource_monitoring 02:48:43 INFO - Starting resource monitoring. 02:48:43 INFO - ##### 02:48:43 INFO - ##### Running install step. 02:48:43 INFO - ##### 02:48:43 INFO - Running pre-action listener: _resource_record_pre_action 02:48:43 INFO - Running main action method: install 02:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:43 INFO - Reading from file tmpfile_stdout 02:48:43 INFO - Detecting whether we're running mozinstall >=1.0... 02:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:48:43 INFO - Reading from file tmpfile_stdout 02:48:43 INFO - Output received: 02:48:43 INFO - Usage: mozinstall [options] installer 02:48:43 INFO - Options: 02:48:43 INFO - -h, --help show this help message and exit 02:48:43 INFO - -d DEST, --destination=DEST 02:48:43 INFO - Directory to install application into. [default: 02:48:43 INFO - "/builds/slave/test"] 02:48:43 INFO - --app=APP Application being installed. [default: firefox] 02:48:43 INFO - mkdir: /builds/slave/test/build/application 02:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:49:16 INFO - Reading from file tmpfile_stdout 02:49:16 INFO - Output received: 02:49:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:49:16 INFO - Running post-action listener: _resource_record_post_action 02:49:16 INFO - ##### 02:49:16 INFO - ##### Running run-tests step. 02:49:16 INFO - ##### 02:49:16 INFO - Running pre-action listener: _resource_record_pre_action 02:49:16 INFO - Running main action method: run_tests 02:49:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:16 INFO - 2016-04-17 02:49:16.089 screenresolution[1946:16020] starting screenresolution argv=screenresolution get 02:49:16 INFO - 2016-04-17 02:49:16.092 screenresolution[1946:16020] Display 0: 1600x1200x32@60 02:49:16 INFO - 2016-04-17 02:49:16.098 screenresolution[1947:16022] starting screenresolution argv=screenresolution list 02:49:16 INFO - Available Modes on Display 0 02:49:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:16 INFO - Intel Iris: 02:49:16 INFO - Chipset Model: Intel Iris 02:49:16 INFO - Type: GPU 02:49:16 INFO - Bus: Built-In 02:49:16 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:16 INFO - Vendor: Intel (0x8086) 02:49:16 INFO - Device ID: 0x0a2e 02:49:16 INFO - Revision ID: 0x0009 02:49:16 INFO - Displays: 02:49:16 INFO - Display: 02:49:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:16 INFO - Display Serial Number: 1600x1200 60 02:49:16 INFO - Main Display: Yes 02:49:16 INFO - Mirror: Off 02:49:16 INFO - Online: Yes 02:49:16 INFO - Rotation: Supported 02:49:16 INFO - Return code: 0 02:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:49:16 INFO - Python 2.7.3 02:49:16 INFO - Return code: 0 02:49:16 INFO - grabbing minidump binary from tooltool 02:49: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')]} 02:49:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10fa07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:49:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:49:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:49:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:49:16 INFO - Return code: 0 02:49:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:49:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:49:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:49:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:49:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:49:17 INFO - Return code: 0 02:49:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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 02:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0078 --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 02:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.glFmtWhVtG/Render', 02:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:17 INFO - 'HOME': '/Users/cltbld', 02:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:17 INFO - 'LOGNAME': 'cltbld', 02:49:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:49:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:17 INFO - 'MOZ_NO_REMOTE': '1', 02:49:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:49:17 INFO - 'NO_EM_RESTART': '1', 02:49:17 INFO - 'PAGER': '/bin/cat', 02:49:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:17 INFO - 'PWD': '/builds/slave/test', 02:49:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:17 INFO - 'SHELL': '/bin/bash', 02:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gC7MgLOBNC/Listeners', 02:49:17 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 02:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:17 INFO - 'USER': 'cltbld', 02:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:17 INFO - 'XPC_FLAGS': '0x0', 02:49:17 INFO - 'XPC_SERVICE_NAME': '0', 02:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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 02:49:17 INFO - mozversion application_buildid: 20160417004052 02:49:17 INFO - mozversion application_changeset: 02b8dc35ec80a95dd4f1cdd5a4075718aed233f2 02:49:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:49:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:49:17 INFO - mozversion application_name: Firefox 02:49:17 INFO - mozversion application_remotingname: firefox-dev 02:49:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:49:17 INFO - mozversion application_vendor: Mozilla 02:49:17 INFO - mozversion application_version: 47.0a2 02:49:17 INFO - mozversion platform_buildid: 20160417004052 02:49:17 INFO - mozversion platform_changeset: 02b8dc35ec80a95dd4f1cdd5a4075718aed233f2 02:49:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:49:17 INFO - mozversion platform_version: 47.0a2 02:49:17 INFO - using testdate: 1460886557 02:49:17 INFO - actual date: 1460886557 02:49:17 INFO - starting webserver on 'localhost:49387' 02:49:17 INFO - SUITE-START | Running 2 tests 02:49:17 INFO - TEST-START | dromaeo_css 02:49:17 INFO - operating with platform_type : mac_ 02:49:17 INFO - Initialising browser for dromaeo_css test... 02:49:17 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpHoP4m_/profile http://localhost:49387/getInfo.html) 02:49:20 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 02:49:20 INFO - PROCESS | 1965 | colorDepth:24 02:49:20 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 02:49:20 INFO - PROCESS | 1965 | __metrics 02:49:20 ERROR - PROCESS | 1965 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 02:49:21 INFO - TEST-INFO | 1965: exit 0 02:49:21 INFO - Browser initialized. 02:49:21 INFO - Running cycle 1/1 for dromaeo_css test... 02:49:21 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpHoP4m_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:33 INFO - PROCESS | 1969 | RSS: Main: 217792512 02:49:33 INFO - PROCESS | 1969 | 02:50:23 INFO - PROCESS | 1969 | 1460886623064 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] 02:52:34 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49387/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49387/tests/dromaeo/cssquery-ext.html) 02:52:35 INFO - PROCESS | 1969 | RSS: Main: 229801984 02:52:35 INFO - PROCESS | 1969 | 02:55:37 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49387/tests/dromaeo/cssquery-ext.html (next: http://localhost:49387/tests/dromaeo/cssquery-jquery.html) 02:55:38 INFO - PROCESS | 1969 | RSS: Main: 240402432 02:55:38 INFO - PROCESS | 1969 | 02:58:40 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49387/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49387/tests/dromaeo/cssquery-mootools.html) 02:58:40 INFO - PROCESS | 1969 | RSS: Main: 242491392 02:58:40 INFO - PROCESS | 1969 | 03:01:42 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49387/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49387/tests/dromaeo/cssquery-prototype.html) 03:01:43 INFO - PROCESS | 1969 | RSS: Main: 243957760 03:01:43 INFO - PROCESS | 1969 | 03:04:45 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49387/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49387/tests/dromaeo/cssquery-yui.html) 03:04:46 INFO - PROCESS | 1969 | RSS: Main: 246685696 03:04:46 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49387/tests/dromaeo/cssquery-yui.html (next: http://localhost:49387/tests/dromaeo/cssquery-dojo.html) 03:07:48 INFO - PROCESS | 1969 | RSS: Main: 248422400 03:07:48 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | __start_tp_report 03:07:48 INFO - PROCESS | 1969 | _x_x_mozilla_page_load 03:07:48 INFO - PROCESS | 1969 | _x_x_mozilla_page_load_details 03:07:48 INFO - PROCESS | 1969 | |i|pagename|runs| 03:07:48 INFO - PROCESS | 1969 | |0;dojo.html;3215.6623554526773;3549.7870127792326;3591.4433262844236;3610.6750392464673;3618.1859081706634;5858.004139296319;5939.495142912852;5940.198073260109;5954.702264886754;5973.761049558023;5930.911036334458;5995.730192141354;6012.3085845127825;6014.278286605602;6037.39626037396;5333.786648534054;5353.330833645794;5355.758990845412;5413.02565538203;5524.088525393306;5268.683878967255;5388.191771234307;5401.540869026137;5409.56723462122;5492.066348720727;5351;5377.892442151155;5416.972915135419;5488.506034456898;5488.670679759207;5402.324709411324;5420.83737487874;5438.53772429344;5438.972805135949;5442.619016668835;5256.737163141827;5350.27771250884;5371.301730774974;5371.597130215249;5461.918071228934;5450.209719590637;5802.854928626776;5816.592838501307;5864.648121112747;5868.618539794942;6157.907631385533;6202.379762023762;6210.720517576675;6256.374362563753;6258.561900666955;6170.352113028112;6268.843278918063;6314.652694101823;6341.904871426932;6361.427471527539;4994.525520075624;6085.178500902366;6120.418560236771;6124.969375153185;6141.969290153565;6424.068510066014;6432;6456.612603243727;6462.5153113515735;6470.288268290485;6151.446369826737;6190.845228869314;6200.596961197508;6270.529710271748;6283.560150789401;6296.905546416808;6314.526410519229;6328.145700330489;6329.810105696836;6345.777897773556;5061.021516028074;6190.009598464224;6190.7523699052435;6230.345813689588;6238.688065596702;5146.871328216825;5760.423957604206;6174.475169610543;6199.225096862892;6220.34686357935;4533.659975501851;4693.131770622377;5019.623528235324;5028.044671512401;5044.747762611854;4493.865184044484;4522.412086428743;4522.66080043998;4544.091181763661;4575.542445755398;5771.076627739541;5867.559933005042;5931.169636250928;6001.279846418458;6023.69881505923;6322.778702745381;6325.177726895474;6336.936630633634;6347;6371.74513019474;6013.098035294741;6021.668808215591;6048.334683184846;6050.546209034252;6063.151158837766;5378.220158077057;5462.781488740486;5508.55931525477;5536.557075433956;5559.63862348946;6463.450606698483;6505.804825855137;6524.314946930599;6532.706028228741;6550.737970481127;5334.546563542142;6115.940154142518;6269.780557680459;6310.11658367838;6313.589616674902;6443.259025212161;6540.117084193763;6579.111819904443;6592.934070659232;6597.769078082244;6215.720292586935;6244.438000579969;6269.153664255267;6284.214473190851;6293.716782744696;5129.410117836496;6244.812655620339;6278.089676996901;6281.403266689657;6296.2759282681245;6159.876802464015;6169.96915015422;6192.969035154795;6231.563790534619;6239.188905442264;6244.65654389013;6255.71849266775;6260.874782504416;6262.467690246198;6267.059941008885;6048.3649216833355;6076.270847498328;6125.173101631401;6127;6193.535484838565;6222.471089957223;6228.906566401598;6232.750689972531;6240.469560087262;6245.750169993149;6150.784722534869;6212.937870621417;6213.130161777445;6214.875702486015;6216.502679785697 03:07:48 INFO - PROCESS | 1969 | |1;ext.html;460.35090522363475;466.63369255134717;610.0361428942272;613.9378874547036;617.5888095701323;39292.41061384082;40401;40474.59525404745;40502.392464112985;40608.796956015176;41855.953601159985;42056.36915446271;42125.36811947823;42397.36403953943;42440.7267781967;9606.711798646034;9794.706158815246;9816.312858099936;9853.605855765762;9866.45734484603;9644.903550964471;9812.264080193978;9860.260480463958;9915.752106197331;9950.452725100153;9839.55721992512;9880.16018638413;9895.703128906145;9904.90095099047;9919;9802;9832.115109640166;9845.753856153618;9869.407835529819;9900.55447504864;9759.902400976041;9779.559919803625;9852.162566181883;9892.90107098927;9899.653512127114;39936.400953985714;40392.99017524556;40393.79803100995;40421.595784042074;40807.387889181686;2905.7384835364705;3089.7528197744127;3114.769665981947;3206.6312374077047;3356.5133055706788;27037.783299751558;27435.588466173023;28585.57121643177;29615.40769184604;30487.695123048707;2796.846173460459;2803.957940630892;2826.2086615747626;2833.773298136144;2842.6020357150014;510897.89102108876;511768;512136;515663;515721;48924.26613600799;50092;72437.63781181166;73180;73261.63369183226;44993.325100123526;45100.54899451029;45215.54784452179;45270.77364613156;45375.31937020947;15152.848471515365;16757.832421675626;17194.656106877792;17298.567535811453;17517.7372339415;13601.523946661819;13796.517121900686;13873.890088792872;13877.167369957835;13885.305734713425;13370.532031379049;15680.686386272439;15909.840901591067;15955.760663590056;16003;6069.968105422001;6492.513061520405;6599.70301336441;6659.500537459709;6666;907.9241099297353;965.6378857928277;1072.367303291062;1118.1502058435647;1165.347405452949;30901.91843285474;30906.0728178155;31099.067027989197;31332.216694582818;31548.211294717814;908.4503875155435;1116.849225354583;1133.8525991621036;1139.6751925701135;1201.8738032506635;14213.715725685428;15545.378184872705;15838.049717017017;15998.360065597479;16013.59966000836;483928.1607183954;535507;537584;544687;545052;62980.685096575144;65756.6712166436;65786.67106664435;65870.67064664645;65985;1475.144416238579;1507.4573153664671;1514.681916797463;1516.3403919295142;1522.5204060720837;29874.85062574673;30628.693713062585;30980;31018.844905775328;31137.377252455277;55593;55703.60740981508;55974.1603875934;56161.71919140378;56413.717931411724;512146;513159.8684013112;515116;515628;518526;35335.82332088323;35808.925732228076;35872.28255434929;36058.819705901304;36433;47969.80075498141;48172.27741583809;48655.51344486651;49408.50591494183;49733;39065.2186956265;39330.01674958035;40040.59959400369;40114.79942600268;40290;32023.03930882077;39109;39174.412383813695;39256;39771.80114099411;39183;39223.607763921995;39826.203475930895;39993;40134;31941.041768746047;34737.19324275294;34750.652493475754;35266.29467410689;35307.82346088253;26109.73890261149;26148.73851261463;26433.603495947962;26481.808318625324;26619.20142395731 03:07:48 INFO - PROCESS | 1969 | |2;jquery.html;281.2883404985388;358.8815690822029;371.867986864663;375.32816258896577;384.3734712418757;20632.690509642347;20747.23648489878;21901.856671526908;22057.448563785914;22106.668399973994;18297.268109275647;21755.912204389795;22087.895605219754;22510.66234006491;22647.77352226477;5985.9102113468225;6119.877602447948;6124.877502449948;6137.723802428879;6138.447539721425;5739.62692424991;6081.939180608181;6122.938770612282;6125.234345706786;6134.539909506782;6092.360302168253;6097.390260973912;6104.664243466607;6113.388661133896;6118.021116621341;5810.970945145289;6044.758209671608;6086.878262434727;6087.421694938996;6088.847778805521;5695.829125126253;5717.113847353667;5740.225069615591;5760.020796464569;5780.971095144498;13586.728265434636;13869.722605547933;13887.02790804644;22567.887160564256;25552.977880884744;1904.209752952523;1986.9965667338047;2414.2998530426157;2520.760527749861;2614.620879972393;9998.250131240186;10081.44552049637;10156.136728378096;10281.434521101337;10326.070653641209;1515.1060874084233;1634.2809163967815;1696.4062578097567;1787.901665408402;1862.4399653961932;74399;74806.25193748047;76979.61510192469;114310.42844785722;115880;11250.987411133037;11612.593559225386;11898.940505297418;11913.70215744596;12022.398880055962;14071;14429.91775616833;14586;14640.414383424757;14649;7035.190953040364;7123.608201548861;9101.271898248124;9292.16370526656;9299.953500232456;9502.429854208769;9514.809703805886;9543.856842147374;9559.522023898917;9619.51902404877;3835.3096442640594;4399.9660079881205;4655.674102812771;4669.556392142741;4682.508336624674;1774.5563609097726;2016.374923773635;2036.6334059869068;2063;2063.9587208255753;795.8408318336355;797.2864286463582;854.3250831842774;881.9647214111492;964.1274646444838;15554.611134721496;17968.1015949202;20367.65691976806;20584.691229631568;21618.89190554037;846.8778867999924;1045.132539991805;1048.0253364371208;1059.0733108530037;1094.3215206571977;11206.140350877226;11410.201285909907;11564.653060408202;11582.652520424232;12095.334756588472;675642;676617;678886.605566969;687739;693325;123790;125106;127085.36457317654;128146.71853281348;128199;997.4464172384252;997.5560875410373;1071.1751950997689;1097.725568607864;1124.1624989382963;15272.694546108793;16271.75592366139;18737.969411682487;18776.812231877506;19104.240194859958;55498.44501555094;56619.71690141523;57022.14466782918;57803.42196578148;58479.4152058491;82248.58875705583;83695.581522092;83853;84561.57719211365;85335;8351.95824020876;8416.452930559493;8434.70478533273;8448;8636.481811091355;8091.797705057421;9400.905990940004;9559;12731.363431828186;13524.985626077887;9628.32601717901;11186.83219751688;11508.251963622337;11643.47604357789;11944.343061131722;10458.529366178693;11357.772844542898;11771.470283837076;11782.351970641354;11862.22895511789;11849.170558060852;11916.344601047025;12036.518539258332;12048.397580120783;12085;8368;8409.2431681149;8424.241818236389;8473.66105355779;8497.915020849712;10066.144377727682;11434.485448154686;11641.941790290994;12000.699982500508;12060.396980151132 03:07:48 INFO - PROCESS | 1969 | |3;mootools.html;1661.393591339161;1688.240291868659;2023.423324352559;2056.9897150514244;2098.4124445155344;7474.887876681847;8248.58757062146;8911.420757650749;9236.44581325121;9259.398139120953;8960.507172105532;8988.325875559327;9075.273978081737;9092.545372731354;9096.0903909609;2565.807564432675;2574.1119313836743;2606.582946728524;2607.1657069737694;2607.4263661994332;2269.8083506159232;2316;2490.514349701809;2549.3626593351664;2588.779953703928;2534.847909125449;2576.793856491476;2597.5584150694426;2603.934901627465;2604.0625374865035;2581.819272650915;2587.288495663698;2608.569586018314;2614.5685961816275;2614.830036047651;2580.6516120323704;2582.496413199427;2611.138324352978;2626;2627.4482358704695;9185.357025008252;9200.217981471584;9213.953930230373;9236.399634023766;9250.907490925072;5783.768649254025;5784.82645520635;5866.2960444746905;5958.493528050121;5959.731812068468;3647.2340808430263;3755.7183211259266;3793.3551296279697;3800.3539398302355;3805;792.579932635704;1034.8085604163255;1060.4538662588773;1076.4671487613602;1097.226455348977;12397.62807115788;12436.880680738686;12500.937420319284;12505.74988500225;12522.749545009048;4683.805629564507;4721.480637129913;4964.90070198594;5049.798008079636;5134.7946082156295;10515.211359148094;10609;10617.097546708463;10629.84055239172;10645.627403040857;5516.924199781004;5774.162746401748;5796.62321949072;5798.449147330997;5894.941050589525;4694.741789201558;4700.412448443944;5088.440271570124;5135.640505164677;5181.948180518147;3762.1535154590547;4077.734947228421;4086.6526345260986;4148.128892932457;4194.790260486963;1142.5601143559788;1377.9104358216684;1383.3429121167567;1388.590365842079;1395.0792476965155;1363.6863521390135;1366.549038817188;1375.9793603095961;1411.9505817296345;1434.211183848879;8345.873307103464;8369.58152092393;8480.660773569112;8515.829683406297;8540.487570745776;525.9842204733864;531.7607076815449;534.5402953460022;535.6705626039958;536.4822945857188;6336.714847831859;6338.714757835908;6409.102725618417;6450.322716114834;6474.255460621995;10743.677689669323;11060.446977651085;11089.27919685218;11145.944270278596;11158.776824463628;10633.627823026312;10925.289856159325;11031.61389351385;11068.778624427467;11150.888491115147;8563.015253245832;8574.013988391414;8583.65665373378;8601.397902146915;8626.223639872316;8003.59982000886;8087.353011759163;8131.146229645802;8324.958375208085;8357.832843343222;8022.438429309892;9478.857817132886;9648.179904708179;9746.415215087118;9788.265880059167;9792.51037448139;10057.949710251694;10104.292699510963;10145.746356341151;10246.026627470379;6948.930510694829;7691.538507689333;7711.305982461606;7759.379249660126;7814.648340824563;8376.371772117238;8455.534945578052;8530.14698530142;8543.700970466003;8590.1839325264;7360.852782944418;7527.962360188164;7563.886541701769;7637.389008879165;7644.967929329692;7408.296211859864;7445.739399121005;7488.138864030706;7517.097948246245;7576.090869095744;7907.2488113629115;7911.564863932539;7920.603969801602;7982.640781164978;7983.760487185394;7106.502544822019;7248.238934911758;7256.7822965311125;7312.817179570553;7381.335679788845;7384.778456646309;7468.925310746823;7479.70081196746;7548.433867460072;7568.318851303409 03:07:48 INFO - PROCESS | 1969 | |4;prototype.html;336.6599734268388;337.2462546209222;342.1514643683666;345.319720151302;350.7982909826849;6937.653117344132;6970.7560235391775;6994;7087.85824283514;7088.113985751781;5541.362743284523;6896.48276379271;6968.512204145712;6989.580625162494;7013.719451221945;4870.123377791996;4982.825601103962;5098.337216161894;5176.404713457944;5181.792728290864;4170.311898536738;5148.510891465305;5164.122099243119;5172.793088276464;5200.453952335007;4598.931016034762;4699.90600187996;5110.923336149961;5168.198929165967;5168.689878607277;5139.100657384943;5153.665011774223;5153.716545589991;5154.92267615986;5155.4586768389345;4402.163588918127;4430.468343798732;4435.201663700533;4438.112377524508;4447.73313601185;5507.917381239285;6406.583572067801;6531.771388001444;6758.9324106758795;6769.559978601375;5322.627416080876;5967.403259673998;5989.490893274077;6022.488088512482;6056.394360563952;7383.150937642186;7396.889046664304;7397.371223446014;7406.8888966665545;7428.925710742931;4900.901981960341;4908.877278068041;4919.631027672903;4921.212605983062;4949.678270912379;7609.695612175562;7888.56612886291;7889.1716369780925;7890.0926393464915;7904.20957904205;4984.22744474607;5033.194688849767;5034.967831594588;5044.394672639233;5044.974775126101;5085.64400491962;5131.589472842164;5138.820141295037;5149.794008239628;5150.819721309811;5029.974850125726;5829.912551311733;6014.548908831855;6023.27720673522;6037.366076561985;7461.291177338144;7468.85062298751;7496.662650180755;7499.62501874915;7505.061867266591;1781.2963879267875;1790.4539115569617;1792.829681180286;1793.560577658456;1793.8116497767808;1055.472263868066;1083.951222195003;1095.2935356694886;1095.4303762043692;1097.4074000040048;6773.593584384953;6882.7591034313555;6895.379415852597;6911.7926462206215;6928.44572434204;6036.426539478743;7192.172900116449;7690.2694244046725;7693.269139431745;7706.152323244439;6830.590164590041;6910.689018994158;6918.446524278046;6948.513604047668;6949.478789090838;4038.0510580013674;4080.449139366207;4081.3265811140827;4095.4471146394853;4126.216018956388;5901.940980590139;7568.735094271674;8130.227628375294;8142.267195952392;8148.185181481923;5856;6817.795466136024;6970.930290697028;7005.964970175117;7388.963055184797;6407.647579383179;7411.888821667571;8198.713045043514;8204.220599042961;8227.382946278933;6418.93581064202;7706.113796913202;7835.177306382748;7840.255175758294;7860.135385107519;5696.344920334214;6613.875641141114;7232.819179520344;7659.348955338635;7667;7897.565633890191;8111.837763244584;8161.959190204011;8174.591270436574;8214.63034163476;4837.419509658864;4848.248521479177;4898.951010489849;4901.14230009754;4901.656884018084;7759.883601746092;7762.379009679099;7774.455788094779;7779.649915753692;7807.531548107132;7467.775966721007;7469.365103966006;7475.476716629783;7476.775696729107;7477.364424024019;7252.121289902166;7370.189279179393;7472.14070381913;7477.4765766398;7484.663190156565;5950.34546199909;6902.930970690229;7253.3834624057545;7263;7306.050213472215;7473.551586904803;7478.4765066447;7479.513831600929;7481.952526646165;7487.850242995;7288.708451661874;7292.489525733148;7294.927050729637;7315.378192853667;7315.805258422559 03:07:48 INFO - PROCESS | 1969 | |5;yui.html;669.4978765925556;715.926378904036;727.138341065837;735.2500449541466;749.4229443328637;15412.845871541282;15573.299201535943;15741.685166296667;15828.208589570519;15841.28714207862;13319.33403329832;14673.706525869477;14697.55907322782;14731.558053258392;14779.187144707037;964.1852634523816;966.9903300967006;971.0192705367559;978.041519311072;980.2648013989508;960.6973803251988;967.4533888353093;970.4419958523877;974.780674348273;975.7024107647164;1008.4755926917999;1012.3217444312328;1019.9592016319339;1024.6311327921942;1025.2310766924807;987.8172538080478;998.1166667499243;1009.0313299232708;1010.9241806864441;1014.2646581228587;760.2701406649624;762.3329586611715;764.9885251721229;765.1392183793232;766.8389638175989;13787.931060344634;13974.860251397458;14011.229382383965;14037.22795246261;14055.578332650037;11790.410479475991;11801.055915526736;11810;11870.643880683507;11917.523299068114;9525.714228573153;9612.807743845155;9641.228701703847;9834.754131146707;9975.102240798364;3390.7117894978956;3449.741269404805;3466.861325546975;3507.386207413718;3551.3430015447225;59280.81438371252;72430;75164.24835751628;79746;86333.56833215794;9524.523773811281;9809.607615695435;9885.950570247103;9929.5317107302;10711.625093121704;21929.561408771737;22132.66800997986;22229.444263893532;22233;22241.888790555942;6502.642354670444;7498.700051997859;7530.171681115073;7613.9238607614325;7659.387249020065;4179.895502612459;4180.623743863067;4244.660427165819;4309.612134907873;4317.611414972667;3708.2212735325884;3723.255348930225;3737.364648009845;3738.2897249522503;3766.6986641068647;674.140470899607;681.9215790184093;688.0711040095831;693.2166651683666;696.1646024770296;595.4849055566904;601.5007543738686;607.7083000159948;608.9908651370233;611.4802417944711;8466.365022623328;8610.827783444296;8616.827663446697;8723.389362744547;8868.290536757173;515.1268599723433;567.8864227154503;577.408156639448;594.3551246897165;642.3929386729495;3346.748993825473;3874.8256328465286;3919.7452165609143;3972.82122304497;4001.579834117434;99688.50155749175;99944.50083248757;101051;101907;102060;74548.88176677354;74965.25034749691;79456.20543794488;86080.56959715161;86155.27689446176;68937.96593051108;69471.30528694749;70301.2969870305;70404;70624.94062588962;6295.527835412271;8676.305895528469;8731.869021964803;8739;8927.642894284156;50120.74939625279;51449.228261576856;52563.737181313845;53010;53449.198262025326;70663.64668176626;74175.62912185404;74337.62831185809;75271.87092193731;75282.62358688172;10981.17641176931;11169.832452513381;11216.495257713259;11490.08079353633;11701.414929253333;11699;11880.980233360176;12164.635060948185;12412.255264684149;12976.610701678965;11713.648590542298;11741.706457338294;11773.234739741889;11908.523659053888;12240.632781016584;11814.940925295663;11823.52705891789;11842.46708898119;11968.820467693165;12002.579909703118;11644.41777911118;11655.708607284885;11693.356865372487;11707.356095414834;11803.645890623295;9752.804943900941;9824.705258842247;9900.75248118774;9957.651482198089;10233.130183934449;9839.803203936024;9967.053129952643;9993.900060999587;10079.34484258521;10105.848412273675 03:07:48 INFO - PROCESS | 1969 | __end_tp_report 03:07:48 INFO - PROCESS | 1969 | __start_cc_report 03:07:48 INFO - PROCESS | 1969 | _x_x_mozilla_cycle_collect,367 03:07:48 INFO - PROCESS | 1969 | __end_cc_report 03:07:48 INFO - PROCESS | 1969 | __startTimestamp1460887668639__endTimestamp 03:07:48 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | ------- Summary: start ------- 03:07:48 INFO - PROCESS | 1969 | Number of tests: 6 03:07:48 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | [#0] dojo.html Cycles:165 Average:5852.16 Median:6125.17 stddev:643.53 (10.5%) stddev-sans-first:611.36 03:07:48 INFO - PROCESS | 1969 | Values: 3215.7 3549.8 3591.4 3610.7 3618.2 5858.0 5939.5 5940.2 5954.7 5973.8 5930.9 5995.7 6012.3 6014.3 6037.4 5333.8 5353.3 5355.8 5413.0 5524.1 5268.7 5388.2 5401.5 5409.6 5492.1 5351.0 5377.9 5417.0 5488.5 5488.7 5402.3 5420.8 5438.5 5439.0 5442.6 5256.7 5350.3 5371.3 5371.6 5461.9 5450.2 5802.9 5816.6 5864.6 5868.6 6157.9 6202.4 6210.7 6256.4 6258.6 6170.4 6268.8 6314.7 6341.9 6361.4 4994.5 6085.2 6120.4 6125.0 6142.0 6424.1 6432.0 6456.6 6462.5 6470.3 6151.4 6190.8 6200.6 6270.5 6283.6 6296.9 6314.5 6328.1 6329.8 6345.8 5061.0 6190.0 6190.8 6230.3 6238.7 5146.9 5760.4 6174.5 6199.2 6220.3 4533.7 4693.1 5019.6 5028.0 5044.7 4493.9 4522.4 4522.7 4544.1 4575.5 5771.1 5867.6 5931.2 6001.3 6023.7 6322.8 6325.2 6336.9 6347.0 6371.7 6013.1 6021.7 6048.3 6050.5 6063.2 5378.2 5462.8 5508.6 5536.6 5559.6 6463.5 6505.8 6524.3 6532.7 6550.7 5334.5 6115.9 6269.8 6310.1 6313.6 6443.3 6540.1 6579.1 6592.9 6597.8 6215.7 6244.4 6269.2 6284.2 6293.7 5129.4 6244.8 6278.1 6281.4 6296.3 6159.9 6170.0 6193.0 6231.6 6239.2 6244.7 6255.7 6260.9 6262.5 6267.1 6048.4 6076.3 6125.2 6127.0 6193.5 6222.5 6228.9 6232.8 6240.5 6245.8 6150.8 6212.9 6213.1 6214.9 6216.5 03:07:48 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | [#1] ext.html Cycles:180 Average:66415.84 Median:40397.40 stddev:138177.75 (342.0%) stddev-sans-first:138476.64 03:07:48 INFO - PROCESS | 1969 | Values: 460.4 466.6 610.0 613.9 617.6 39292.4 40401.0 40474.6 40502.4 40608.8 41856.0 42056.4 42125.4 42397.4 42440.7 9606.7 9794.7 9816.3 9853.6 9866.5 9644.9 9812.3 9860.3 9915.8 9950.5 9839.6 9880.2 9895.7 9904.9 9919.0 9802.0 9832.1 9845.8 9869.4 9900.6 9759.9 9779.6 9852.2 9892.9 9899.7 39936.4 40393.0 40393.8 40421.6 40807.4 2905.7 3089.8 3114.8 3206.6 3356.5 27037.8 27435.6 28585.6 29615.4 30487.7 2796.8 2804.0 2826.2 2833.8 2842.6 510897.9 511768.0 512136.0 515663.0 515721.0 48924.3 50092.0 72437.6 73180.0 73261.6 44993.3 45100.5 45215.5 45270.8 45375.3 15152.8 16757.8 17194.7 17298.6 17517.7 13601.5 13796.5 13873.9 13877.2 13885.3 13370.5 15680.7 15909.8 15955.8 16003.0 6070.0 6492.5 6599.7 6659.5 6666.0 907.9 965.6 1072.4 1118.2 1165.3 30901.9 30906.1 31099.1 31332.2 31548.2 908.5 1116.8 1133.9 1139.7 1201.9 14213.7 15545.4 15838.0 15998.4 16013.6 483928.2 535507.0 537584.0 544687.0 545052.0 62980.7 65756.7 65786.7 65870.7 65985.0 1475.1 1507.5 1514.7 1516.3 1522.5 29874.9 30628.7 30980.0 31018.8 31137.4 55593.0 55703.6 55974.2 56161.7 56413.7 512146.0 513159.9 515116.0 515628.0 518526.0 35335.8 35808.9 35872.3 36058.8 36433.0 47969.8 48172.3 48655.5 49408.5 49733.0 39065.2 39330.0 40040.6 40114.8 40290.0 32023.0 39109.0 39174.4 39256.0 39771.8 39183.0 39223.6 39826.2 39993.0 40134.0 31941.0 34737.2 34750.7 35266.3 35307.8 26109.7 26148.7 26433.6 26481.8 26619.2 03:07:48 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | [#2] jquery.html Cycles:180 Average:36725.27 Median:2467.53 stddev:112853.81 (4573.6%) stddev-sans-first:113137.21 03:07:48 INFO - PROCESS | 1969 | Values: 281.3 358.9 371.9 375.3 384.4 20632.7 20747.2 21901.9 22057.4 22106.7 18297.3 21755.9 22087.9 22510.7 22647.8 5985.9 6119.9 6124.9 6137.7 6138.4 5739.6 6081.9 6122.9 6125.2 6134.5 6092.4 6097.4 6104.7 6113.4 6118.0 5811.0 6044.8 6086.9 6087.4 6088.8 5695.8 5717.1 5740.2 5760.0 5781.0 13586.7 13869.7 13887.0 22567.9 25553.0 1904.2 1987.0 2414.3 2520.8 2614.6 9998.3 10081.4 10156.1 10281.4 10326.1 1515.1 1634.3 1696.4 1787.9 1862.4 74399.0 74806.3 76979.6 114310.4 115880.0 11251.0 11612.6 11898.9 11913.7 12022.4 14071.0 14429.9 14586.0 14640.4 14649.0 7035.2 7123.6 9101.3 9292.2 9300.0 9502.4 9514.8 9543.9 9559.5 9619.5 3835.3 4400.0 4655.7 4669.6 4682.5 1774.6 2016.4 2036.6 2063.0 2064.0 795.8 797.3 854.3 882.0 964.1 15554.6 17968.1 20367.7 20584.7 21618.9 846.9 1045.1 1048.0 1059.1 1094.3 11206.1 11410.2 11564.7 11582.7 12095.3 675642.0 676617.0 678886.6 687739.0 693325.0 123790.0 125106.0 127085.4 128146.7 128199.0 997.4 997.6 1071.2 1097.7 1124.2 15272.7 16271.8 18738.0 18776.8 19104.2 55498.4 56619.7 57022.1 57803.4 58479.4 82248.6 83695.6 83853.0 84561.6 85335.0 8352.0 8416.5 8434.7 8448.0 8636.5 8091.8 9400.9 9559.0 12731.4 13525.0 9628.3 11186.8 11508.3 11643.5 11944.3 10458.5 11357.8 11771.5 11782.4 11862.2 11849.2 11916.3 12036.5 12048.4 12085.0 8368.0 8409.2 8424.2 8473.7 8497.9 10066.1 11434.5 11641.9 12000.7 12060.4 03:07:48 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | [#3] mootools.html Cycles:180 Average:6253.34 Median:535.11 stddev:3298.92 (616.5%) stddev-sans-first:3290.12 03:07:48 INFO - PROCESS | 1969 | Values: 1661.4 1688.2 2023.4 2057.0 2098.4 7474.9 8248.6 8911.4 9236.4 9259.4 8960.5 8988.3 9075.3 9092.5 9096.1 2565.8 2574.1 2606.6 2607.2 2607.4 2269.8 2316.0 2490.5 2549.4 2588.8 2534.8 2576.8 2597.6 2603.9 2604.1 2581.8 2587.3 2608.6 2614.6 2614.8 2580.7 2582.5 2611.1 2626.0 2627.4 9185.4 9200.2 9214.0 9236.4 9250.9 5783.8 5784.8 5866.3 5958.5 5959.7 3647.2 3755.7 3793.4 3800.4 3805.0 792.6 1034.8 1060.5 1076.5 1097.2 12397.6 12436.9 12500.9 12505.7 12522.7 4683.8 4721.5 4964.9 5049.8 5134.8 10515.2 10609.0 10617.1 10629.8 10645.6 5516.9 5774.2 5796.6 5798.4 5894.9 4694.7 4700.4 5088.4 5135.6 5181.9 3762.2 4077.7 4086.7 4148.1 4194.8 1142.6 1377.9 1383.3 1388.6 1395.1 1363.7 1366.5 1376.0 1412.0 1434.2 8345.9 8369.6 8480.7 8515.8 8540.5 526.0 531.8 534.5 535.7 536.5 6336.7 6338.7 6409.1 6450.3 6474.3 10743.7 11060.4 11089.3 11145.9 11158.8 10633.6 10925.3 11031.6 11068.8 11150.9 8563.0 8574.0 8583.7 8601.4 8626.2 8003.6 8087.4 8131.1 8325.0 8357.8 8022.4 9478.9 9648.2 9746.4 9788.3 9792.5 10057.9 10104.3 10145.7 10246.0 6948.9 7691.5 7711.3 7759.4 7814.6 8376.4 8455.5 8530.1 8543.7 8590.2 7360.9 7528.0 7563.9 7637.4 7645.0 7408.3 7445.7 7488.1 7517.1 7576.1 7907.2 7911.6 7920.6 7982.6 7983.8 7106.5 7248.2 7256.8 7312.8 7381.3 7384.8 7468.9 7479.7 7548.4 7568.3 03:07:48 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | [#4] prototype.html Cycles:180 Average:6011.87 Median:6824.19 stddev:1907.88 (28.0%) stddev-sans-first:1865.07 03:07:48 INFO - PROCESS | 1969 | Values: 336.7 337.2 342.2 345.3 350.8 6937.7 6970.8 6994.0 7087.9 7088.1 5541.4 6896.5 6968.5 6989.6 7013.7 4870.1 4982.8 5098.3 5176.4 5181.8 4170.3 5148.5 5164.1 5172.8 5200.5 4598.9 4699.9 5110.9 5168.2 5168.7 5139.1 5153.7 5153.7 5154.9 5155.5 4402.2 4430.5 4435.2 4438.1 4447.7 5507.9 6406.6 6531.8 6758.9 6769.6 5322.6 5967.4 5989.5 6022.5 6056.4 7383.2 7396.9 7397.4 7406.9 7428.9 4900.9 4908.9 4919.6 4921.2 4949.7 7609.7 7888.6 7889.2 7890.1 7904.2 4984.2 5033.2 5035.0 5044.4 5045.0 5085.6 5131.6 5138.8 5149.8 5150.8 5030.0 5829.9 6014.5 6023.3 6037.4 7461.3 7468.9 7496.7 7499.6 7505.1 1781.3 1790.5 1792.8 1793.6 1793.8 1055.5 1084.0 1095.3 1095.4 1097.4 6773.6 6882.8 6895.4 6911.8 6928.4 6036.4 7192.2 7690.3 7693.3 7706.2 6830.6 6910.7 6918.4 6948.5 6949.5 4038.1 4080.4 4081.3 4095.4 4126.2 5901.9 7568.7 8130.2 8142.3 8148.2 5856.0 6817.8 6970.9 7006.0 7389.0 6407.6 7411.9 8198.7 8204.2 8227.4 6418.9 7706.1 7835.2 7840.3 7860.1 5696.3 6613.9 7232.8 7659.3 7667.0 7897.6 8111.8 8162.0 8174.6 8214.6 4837.4 4848.2 4899.0 4901.1 4901.7 7759.9 7762.4 7774.5 7779.6 7807.5 7467.8 7469.4 7475.5 7476.8 7477.4 7252.1 7370.2 7472.1 7477.5 7484.7 5950.3 6902.9 7253.4 7263.0 7306.1 7473.6 7478.5 7479.5 7482.0 7487.9 7288.7 7292.5 7294.9 7315.4 7315.8 03:07:48 INFO - PROCESS | 1969 | 03:07:48 INFO - PROCESS | 1969 | [#5] yui.html Cycles:180 Average:18760.40 Median:27008.54 stddev:26606.52 (98.5%) stddev-sans-first:26646.48 03:07:48 INFO - PROCESS | 1969 | Values: 669.5 715.9 727.1 735.3 749.4 15412.8 15573.3 15741.7 15828.2 15841.3 13319.3 14673.7 14697.6 14731.6 14779.2 964.2 967.0 971.0 978.0 980.3 960.7 967.5 970.4 974.8 975.7 1008.5 1012.3 1020.0 1024.6 1025.2 987.8 998.1 1009.0 1010.9 1014.3 760.3 762.3 765.0 765.1 766.8 13787.9 13974.9 14011.2 14037.2 14055.6 11790.4 11801.1 11810.0 11870.6 11917.5 9525.7 9612.8 9641.2 9834.8 9975.1 3390.7 3449.7 3466.9 3507.4 3551.3 59280.8 72430.0 75164.2 79746.0 86333.6 9524.5 9809.6 9886.0 9929.5 10711.6 21929.6 22132.7 22229.4 22233.0 22241.9 6502.6 7498.7 7530.2 7613.9 7659.4 4179.9 4180.6 4244.7 4309.6 4317.6 3708.2 3723.3 3737.4 3738.3 3766.7 674.1 681.9 688.1 693.2 696.2 595.5 601.5 607.7 609.0 611.5 8466.4 8610.8 8616.8 8723.4 8868.3 515.1 567.9 577.4 594.4 642.4 3346.7 3874.8 3919.7 3972.8 4001.6 99688.5 99944.5 101051.0 101907.0 102060.0 74548.9 74965.3 79456.2 86080.6 86155.3 68938.0 69471.3 70301.3 70404.0 70624.9 6295.5 8676.3 8731.9 8739.0 8927.6 50120.7 51449.2 52563.7 53010.0 53449.2 70663.6 74175.6 74337.6 75271.9 75282.6 10981.2 11169.8 11216.5 11490.1 11701.4 11699.0 11881.0 12164.6 12412.3 12976.6 11713.6 11741.7 11773.2 11908.5 12240.6 11814.9 11823.5 11842.5 11968.8 12002.6 11644.4 11655.7 11693.4 11707.4 11803.6 9752.8 9824.7 9900.8 9957.7 10233.1 9839.8 9967.1 9993.9 10079.3 10105.8 03:07:48 INFO - PROCESS | 1969 | -------- Summary: end -------- 03:07:48 INFO - PROCESS | 1969 | 03:07:49 INFO - TEST-INFO | 1969: exit 0 03:07:49 INFO - TEST-OK | dromaeo_css | took 1111319ms 03:07:49 INFO - TEST-START | kraken 03:07:49 INFO - operating with platform_type : mac_ 03:07:49 INFO - Initialising browser for kraken test... 03:07:49 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmphMS2h4/profile http://localhost:49387/getInfo.html) 03:07:51 INFO - PROCESS | 1973 | __metrics Screen width/height:1600/1200 03:07:51 INFO - PROCESS | 1973 | colorDepth:24 03:07:51 INFO - PROCESS | 1973 | Browser inner width/height: 1024/689 03:07:51 INFO - PROCESS | 1973 | __metrics 03:07:51 ERROR - PROCESS | 1973 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 03:07:52 INFO - TEST-INFO | 1973: exit 0 03:07:52 INFO - Browser initialized. 03:07:52 INFO - Running cycle 1/1 for kraken test... 03:07:52 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmphMS2h4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:08:04 INFO - PROCESS | 1977 | RSS: Main: 217722880 03:08:04 INFO - PROCESS | 1977 | 03:08:07 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49387/tests/kraken/driver.html?testName=audio-beat-detection) 03:08:07 INFO - PROCESS | 1977 | RSS: Main: 229904384 03:08:07 INFO - PROCESS | 1977 | 03:08:10 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49387/tests/kraken/driver.html?testName=audio-dft) 03:08:11 INFO - PROCESS | 1977 | RSS: Main: 238796800 03:08:11 INFO - PROCESS | 1977 | 03:08:14 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49387/tests/kraken/driver.html?testName=audio-fft) 03:08:14 INFO - PROCESS | 1977 | RSS: Main: 242257920 03:08:14 INFO - PROCESS | 1977 | 03:08:16 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49387/tests/kraken/driver.html?testName=audio-oscillator) 03:08:17 INFO - PROCESS | 1977 | RSS: Main: 242860032 03:08:17 INFO - PROCESS | 1977 | 03:08:19 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49387/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:08:20 INFO - PROCESS | 1977 | RSS: Main: 243286016 03:08:20 INFO - PROCESS | 1977 | 03:08:23 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49387/tests/kraken/driver.html?testName=imaging-darkroom) 03:08:23 INFO - PROCESS | 1977 | RSS: Main: 243556352 03:08:23 INFO - PROCESS | 1977 | 03:08:26 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49387/tests/kraken/driver.html?testName=imaging-desaturate) 03:08:27 INFO - PROCESS | 1977 | RSS: Main: 244203520 03:08:27 INFO - PROCESS | 1977 | 03:08:30 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49387/tests/kraken/driver.html?testName=json-parse-financial) 03:08:31 INFO - PROCESS | 1977 | RSS: Main: 244498432 03:08:31 INFO - PROCESS | 1977 | 03:08:32 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49387/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:08:33 INFO - PROCESS | 1977 | RSS: Main: 244457472 03:08:33 INFO - PROCESS | 1977 | 03:08:35 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49387/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:08:35 INFO - PROCESS | 1977 | RSS: Main: 244948992 03:08:35 INFO - PROCESS | 1977 | 03:08:37 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49387/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:08:38 INFO - PROCESS | 1977 | RSS: Main: 245280768 03:08:38 INFO - PROCESS | 1977 | 03:08:41 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49387/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:08:41 INFO - PROCESS | 1977 | RSS: Main: 245362688 03:08:41 INFO - PROCESS | 1977 | 03:08:44 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49387/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:08:44 INFO - PROCESS | 1977 | RSS: Main: 244871168 03:08:44 INFO - PROCESS | 1977 | 03:08:46 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49387/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49387/tests/kraken/driver.html?testName=ai-astar) 03:08:47 INFO - PROCESS | 1977 | RSS: Main: 246685696 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | __start_tp_report 03:08:47 INFO - PROCESS | 1977 | _x_x_mozilla_page_load 03:08:47 INFO - PROCESS | 1977 | _x_x_mozilla_page_load_details 03:08:47 INFO - PROCESS | 1977 | |i|pagename|runs| 03:08:47 INFO - PROCESS | 1977 | |0;ai-astar;115;110;114;113;114;130;117;115;115;116 03:08:47 INFO - PROCESS | 1977 | |1;audio-beat-detection;148;120;140;158;115;142;138;120;155;121 03:08:47 INFO - PROCESS | 1977 | |2;audio-dft;191;193;194;194;195;200;197;201;203;201 03:08:47 INFO - PROCESS | 1977 | |3;audio-fft;69;79;71;112;59;65;83;86;160;62 03:08:47 INFO - PROCESS | 1977 | |4;audio-oscillator;87;80;160;116;86;82;119;84;80;119 03:08:47 INFO - PROCESS | 1977 | |5;imaging-gaussian-blur;136;130;94;88;91;87;91;92;128;87 03:08:47 INFO - PROCESS | 1977 | |6;imaging-darkroom;151;104;149;152;92;152;149;95;95;95 03:08:47 INFO - PROCESS | 1977 | |7;imaging-desaturate;92;94;92;93;113;122;104;85;135;116 03:08:47 INFO - PROCESS | 1977 | |8;json-parse-financial;55;61;60;59;59;60;79;58;49;53 03:08:47 INFO - PROCESS | 1977 | |9;json-stringify-tinderbox;62;66;67;71;65;64;75;97;97;97 03:08:47 INFO - PROCESS | 1977 | |10;stanford-crypto-aes;81;83;82;85;80;82;81;84;81;90 03:08:47 INFO - PROCESS | 1977 | |11;stanford-crypto-ccm;137;138;136;120;126;122;130;122;153;134 03:08:47 INFO - PROCESS | 1977 | |12;stanford-crypto-pbkdf2;125;136;138;144;180;174;136;133;163;171 03:08:47 INFO - PROCESS | 1977 | |13;stanford-crypto-sha256-iterative;65;98;90;99;55;66;93;93;97;99 03:08:47 INFO - PROCESS | 1977 | __end_tp_report 03:08:47 INFO - PROCESS | 1977 | __start_cc_report 03:08:47 INFO - PROCESS | 1977 | _x_x_mozilla_cycle_collect,958 03:08:47 INFO - PROCESS | 1977 | __end_cc_report 03:08:47 INFO - PROCESS | 1977 | __startTimestamp1460887727148__endTimestamp 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | ------- Summary: start ------- 03:08:47 INFO - PROCESS | 1977 | Number of tests: 14 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#0] ai-astar Cycles:10 Average:115.90 Median:115.00 stddev:5.30 (4.6%) stddev-sans-first:5.61 03:08:47 INFO - PROCESS | 1977 | Values: 115.0 110.0 114.0 113.0 114.0 130.0 117.0 115.0 115.0 116.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:141.00 stddev:15.71 (11.1%) stddev-sans-first:16.02 03:08:47 INFO - PROCESS | 1977 | Values: 148.0 120.0 140.0 158.0 115.0 142.0 138.0 120.0 155.0 121.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#2] audio-dft Cycles:10 Average:196.90 Median:198.50 stddev:4.09 (2.1%) stddev-sans-first:3.75 03:08:47 INFO - PROCESS | 1977 | Values: 191.0 193.0 194.0 194.0 195.0 200.0 197.0 201.0 203.0 201.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#3] audio-fft Cycles:10 Average:84.60 Median:70.00 stddev:30.64 (43.8%) stddev-sans-first:31.98 03:08:47 INFO - PROCESS | 1977 | Values: 69.0 79.0 71.0 112.0 59.0 65.0 83.0 86.0 160.0 62.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#4] audio-oscillator Cycles:10 Average:101.30 Median:81.00 stddev:26.47 (32.7%) stddev-sans-first:27.57 03:08:47 INFO - PROCESS | 1977 | Values: 87.0 80.0 160.0 116.0 86.0 82.0 119.0 84.0 80.0 119.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#5] imaging-gaussian-blur Cycles:10 Average:102.40 Median:89.50 stddev:20.18 (22.6%) stddev-sans-first:17.36 03:08:47 INFO - PROCESS | 1977 | Values: 136.0 130.0 94.0 88.0 91.0 87.0 91.0 92.0 128.0 87.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#6] imaging-darkroom Cycles:10 Average:123.40 Median:122.00 stddev:28.85 (23.6%) stddev-sans-first:28.82 03:08:47 INFO - PROCESS | 1977 | Values: 151.0 104.0 149.0 152.0 92.0 152.0 149.0 95.0 95.0 95.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#7] imaging-desaturate Cycles:10 Average:104.60 Median:88.50 stddev:16.25 (18.4%) stddev-sans-first:16.58 03:08:47 INFO - PROCESS | 1977 | Values: 92.0 94.0 92.0 93.0 113.0 122.0 104.0 85.0 135.0 116.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:59.50 stddev:7.87 (13.2%) stddev-sans-first:8.20 03:08:47 INFO - PROCESS | 1977 | Values: 55.0 61.0 60.0 59.0 59.0 60.0 79.0 58.0 49.0 53.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#9] json-stringify-tinderbox Cycles:10 Average:76.10 Median:73.00 stddev:14.87 (20.4%) stddev-sans-first:14.87 03:08:47 INFO - PROCESS | 1977 | Values: 62.0 66.0 67.0 71.0 65.0 64.0 75.0 97.0 97.0 97.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:82.50 stddev:2.92 (3.5%) stddev-sans-first:3.02 03:08:47 INFO - PROCESS | 1977 | Values: 81.0 83.0 82.0 85.0 80.0 82.0 81.0 84.0 81.0 90.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#11] stanford-crypto-ccm Cycles:10 Average:131.80 Median:135.00 stddev:10.03 (7.4%) stddev-sans-first:10.46 03:08:47 INFO - PROCESS | 1977 | Values: 137.0 138.0 136.0 120.0 126.0 122.0 130.0 122.0 153.0 134.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.00 Median:153.50 stddev:19.92 (13.0%) stddev-sans-first:18.97 03:08:47 INFO - PROCESS | 1977 | Values: 125.0 136.0 138.0 144.0 180.0 174.0 136.0 133.0 163.0 171.0 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - PROCESS | 1977 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.50 Median:95.00 stddev:16.72 (17.6%) stddev-sans-first:16.01 03:08:47 INFO - PROCESS | 1977 | Values: 65.0 98.0 90.0 99.0 55.0 66.0 93.0 93.0 97.0 99.0 03:08:47 INFO - PROCESS | 1977 | -------- Summary: end -------- 03:08:47 INFO - PROCESS | 1977 | 03:08:47 INFO - TEST-INFO | 1977: exit 0 03:08:47 INFO - TEST-OK | kraken | took 58746ms 03:08:47 INFO - SUITE-END | took 1170s 03:08:48 INFO - Completed test suite (00:19:30) 03:08:48 INFO - javascript benchmark 03:08:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3215.6623554526773, 3549.7870127792326, 3591.4433262844236, 3610.6750392464673, 3618.1859081706634, 5858.004139296319, 5939.495142912852, 5940.198073260109, 5954.702264886754, 5973.761049558023, 5930.911036334458, 5995.730192141354, 6012.3085845127825, 6014.278286605602, 6037.39626037396, 5333.786648534054, 5353.330833645794, 5355.758990845412, 5413.02565538203, 5524.088525393306, 5268.683878967255, 5388.191771234307, 5401.540869026137, 5409.56723462122, 5492.066348720727, 5351.0, 5377.892442151155, 5416.972915135419, 5488.506034456898, 5488.670679759207, 5402.324709411324, 5420.83737487874, 5438.53772429344, 5438.972805135949, 5442.619016668835, 5256.737163141827, 5350.27771250884, 5371.301730774974, 5371.597130215249, 5461.918071228934, 5450.209719590637, 5802.854928626776, 5816.592838501307, 5864.648121112747, 5868.618539794942, 6157.907631385533, 6202.379762023762, 6210.720517576675, 6256.374362563753, 6258.561900666955, 6170.352113028112, 6268.843278918063, 6314.652694101823, 6341.904871426932, 6361.427471527539, 4994.525520075624, 6085.178500902366, 6120.418560236771, 6124.969375153185, 6141.969290153565, 6424.068510066014, 6432.0, 6456.612603243727, 6462.5153113515735, 6470.288268290485, 6151.446369826737, 6190.845228869314, 6200.596961197508, 6270.529710271748, 6283.560150789401, 6296.905546416808, 6314.526410519229, 6328.145700330489, 6329.810105696836, 6345.777897773556, 5061.021516028074, 6190.009598464224, 6190.7523699052435, 6230.345813689588, 6238.688065596702, 5146.871328216825, 5760.423957604206, 6174.475169610543, 6199.225096862892, 6220.34686357935, 4533.659975501851, 4693.131770622377, 5019.623528235324, 5028.044671512401, 5044.747762611854, 4493.865184044484, 4522.412086428743, 4522.66080043998, 4544.091181763661, 4575.542445755398, 5771.076627739541, 5867.559933005042, 5931.169636250928, 6001.279846418458, 6023.69881505923, 6322.778702745381, 6325.177726895474, 6336.936630633634, 6347.0, 6371.74513019474, 6013.098035294741, 6021.668808215591, 6048.334683184846, 6050.546209034252, 6063.151158837766, 5378.220158077057, 5462.781488740486, 5508.55931525477, 5536.557075433956, 5559.63862348946, 6463.450606698483, 6505.804825855137, 6524.314946930599, 6532.706028228741, 6550.737970481127, 5334.546563542142, 6115.940154142518, 6269.780557680459, 6310.11658367838, 6313.589616674902, 6443.259025212161, 6540.117084193763, 6579.111819904443, 6592.934070659232, 6597.769078082244, 6215.720292586935, 6244.438000579969, 6269.153664255267, 6284.214473190851, 6293.716782744696, 5129.410117836496, 6244.812655620339, 6278.089676996901, 6281.403266689657, 6296.2759282681245, 6159.876802464015, 6169.96915015422, 6192.969035154795, 6231.563790534619, 6239.188905442264, 6244.65654389013, 6255.71849266775, 6260.874782504416, 6262.467690246198, 6267.059941008885, 6048.3649216833355, 6076.270847498328, 6125.173101631401, 6127.0, 6193.535484838565, 6222.471089957223, 6228.906566401598, 6232.750689972531, 6240.469560087262, 6245.750169993149, 6150.784722534869, 6212.937870621417, 6213.130161777445, 6214.875702486015, 6216.502679785697], "unit": "score", "name": "dojo.html", "value": 5813.639335688912}, {"lowerIsBetter": false, "replicates": [460.35090522363475, 466.63369255134717, 610.0361428942272, 613.9378874547036, 617.5888095701323, 39292.41061384082, 40401.0, 40474.59525404745, 40502.392464112985, 40608.796956015176, 41855.953601159985, 42056.36915446271, 42125.36811947823, 42397.36403953943, 42440.7267781967, 9606.711798646034, 9794.706158815246, 9816.312858099936, 9853.605855765762, 9866.45734484603, 9644.903550964471, 9812.264080193978, 9860.260480463958, 9915.752106197331, 9950.452725100153, 9839.55721992512, 9880.16018638413, 9895.703128906145, 9904.90095099047, 9919.0, 9802.0, 9832.115109640166, 9845.753856153618, 9869.407835529819, 9900.55447504864, 9759.902400976041, 9779.559919803625, 9852.162566181883, 9892.90107098927, 9899.653512127114, 39936.400953985714, 40392.99017524556, 40393.79803100995, 40421.595784042074, 40807.387889181686, 2905.7384835364705, 3089.7528197744127, 3114.769665981947, 3206.6312374077047, 3356.5133055706788, 27037.783299751558, 27435.588466173023, 28585.57121643177, 29615.40769184604, 30487.695123048707, 2796.846173460459, 2803.957940630892, 2826.2086615747626, 2833.773298136144, 2842.6020357150014, 510897.89102108876, 511768.0, 512136.0, 515663.0, 515721.0, 48924.26613600799, 50092.0, 72437.63781181166, 73180.0, 73261.63369183226, 44993.325100123526, 45100.54899451029, 45215.54784452179, 45270.77364613156, 45375.31937020947, 15152.848471515365, 16757.832421675626, 17194.656106877792, 17298.567535811453, 17517.7372339415, 13601.523946661819, 13796.517121900686, 13873.890088792872, 13877.167369957835, 13885.305734713425, 13370.532031379049, 15680.686386272439, 15909.840901591067, 15955.760663590056, 16003.0, 6069.968105422001, 6492.513061520405, 6599.70301336441, 6659.500537459709, 6666.0, 907.9241099297353, 965.6378857928277, 1072.367303291062, 1118.1502058435647, 1165.347405452949, 30901.91843285474, 30906.0728178155, 31099.067027989197, 31332.216694582818, 31548.211294717814, 908.4503875155435, 1116.849225354583, 1133.8525991621036, 1139.6751925701135, 1201.8738032506635, 14213.715725685428, 15545.378184872705, 15838.049717017017, 15998.360065597479, 16013.59966000836, 483928.1607183954, 535507.0, 537584.0, 544687.0, 545052.0, 62980.685096575144, 65756.6712166436, 65786.67106664435, 65870.67064664645, 65985.0, 1475.144416238579, 1507.4573153664671, 1514.681916797463, 1516.3403919295142, 1522.5204060720837, 29874.85062574673, 30628.693713062585, 30980.0, 31018.844905775328, 31137.377252455277, 55593.0, 55703.60740981508, 55974.1603875934, 56161.71919140378, 56413.717931411724, 512146.0, 513159.8684013112, 515116.0, 515628.0, 518526.0, 35335.82332088323, 35808.925732228076, 35872.28255434929, 36058.819705901304, 36433.0, 47969.80075498141, 48172.27741583809, 48655.51344486651, 49408.50591494183, 49733.0, 39065.2186956265, 39330.01674958035, 40040.59959400369, 40114.79942600268, 40290.0, 32023.03930882077, 39109.0, 39174.412383813695, 39256.0, 39771.80114099411, 39183.0, 39223.607763921995, 39826.203475930895, 39993.0, 40134.0, 31941.041768746047, 34737.19324275294, 34750.652493475754, 35266.29467410689, 35307.82346088253, 26109.73890261149, 26148.73851261463, 26433.603495947962, 26481.808318625324, 26619.20142395731], "unit": "score", "name": "ext.html", "value": 20041.879057363785}, {"lowerIsBetter": false, "replicates": [281.2883404985388, 358.8815690822029, 371.867986864663, 375.32816258896577, 384.3734712418757, 20632.690509642347, 20747.23648489878, 21901.856671526908, 22057.448563785914, 22106.668399973994, 18297.268109275647, 21755.912204389795, 22087.895605219754, 22510.66234006491, 22647.77352226477, 5985.9102113468225, 6119.877602447948, 6124.877502449948, 6137.723802428879, 6138.447539721425, 5739.62692424991, 6081.939180608181, 6122.938770612282, 6125.234345706786, 6134.539909506782, 6092.360302168253, 6097.390260973912, 6104.664243466607, 6113.388661133896, 6118.021116621341, 5810.970945145289, 6044.758209671608, 6086.878262434727, 6087.421694938996, 6088.847778805521, 5695.829125126253, 5717.113847353667, 5740.225069615591, 5760.020796464569, 5780.971095144498, 13586.728265434636, 13869.722605547933, 13887.02790804644, 22567.887160564256, 25552.977880884744, 1904.209752952523, 1986.9965667338047, 2414.2998530426157, 2520.760527749861, 2614.620879972393, 9998.250131240186, 10081.44552049637, 10156.136728378096, 10281.434521101337, 10326.070653641209, 1515.1060874084233, 1634.2809163967815, 1696.4062578097567, 1787.901665408402, 1862.4399653961932, 74399.0, 74806.25193748047, 76979.61510192469, 114310.42844785722, 115880.0, 11250.987411133037, 11612.593559225386, 11898.940505297418, 11913.70215744596, 12022.398880055962, 14071.0, 14429.91775616833, 14586.0, 14640.414383424757, 14649.0, 7035.190953040364, 7123.608201548861, 9101.271898248124, 9292.16370526656, 9299.953500232456, 9502.429854208769, 9514.809703805886, 9543.856842147374, 9559.522023898917, 9619.51902404877, 3835.3096442640594, 4399.9660079881205, 4655.674102812771, 4669.556392142741, 4682.508336624674, 1774.5563609097726, 2016.374923773635, 2036.6334059869068, 2063.0, 2063.9587208255753, 795.8408318336355, 797.2864286463582, 854.3250831842774, 881.9647214111492, 964.1274646444838, 15554.611134721496, 17968.1015949202, 20367.65691976806, 20584.691229631568, 21618.89190554037, 846.8778867999924, 1045.132539991805, 1048.0253364371208, 1059.0733108530037, 1094.3215206571977, 11206.140350877226, 11410.201285909907, 11564.653060408202, 11582.652520424232, 12095.334756588472, 675642.0, 676617.0, 678886.605566969, 687739.0, 693325.0, 123790.0, 125106.0, 127085.36457317654, 128146.71853281348, 128199.0, 997.4464172384252, 997.5560875410373, 1071.1751950997689, 1097.725568607864, 1124.1624989382963, 15272.694546108793, 16271.75592366139, 18737.969411682487, 18776.812231877506, 19104.240194859958, 55498.44501555094, 56619.71690141523, 57022.14466782918, 57803.42196578148, 58479.4152058491, 82248.58875705583, 83695.581522092, 83853.0, 84561.57719211365, 85335.0, 8351.95824020876, 8416.452930559493, 8434.70478533273, 8448.0, 8636.481811091355, 8091.797705057421, 9400.905990940004, 9559.0, 12731.363431828186, 13524.985626077887, 9628.32601717901, 11186.83219751688, 11508.251963622337, 11643.47604357789, 11944.343061131722, 10458.529366178693, 11357.772844542898, 11771.470283837076, 11782.351970641354, 11862.22895511789, 11849.170558060852, 11916.344601047025, 12036.518539258332, 12048.397580120783, 12085.0, 8368.0, 8409.2431681149, 8424.241818236389, 8473.66105355779, 8497.915020849712, 10066.144377727682, 11434.485448154686, 11641.941790290994, 12000.699982500508, 12060.396980151132], "unit": "score", "name": "jquery.html", "value": 9561.449095768383}, {"lowerIsBetter": false, "replicates": [1661.393591339161, 1688.240291868659, 2023.423324352559, 2056.9897150514244, 2098.4124445155344, 7474.887876681847, 8248.58757062146, 8911.420757650749, 9236.44581325121, 9259.398139120953, 8960.507172105532, 8988.325875559327, 9075.273978081737, 9092.545372731354, 9096.0903909609, 2565.807564432675, 2574.1119313836743, 2606.582946728524, 2607.1657069737694, 2607.4263661994332, 2269.8083506159232, 2316.0, 2490.514349701809, 2549.3626593351664, 2588.779953703928, 2534.847909125449, 2576.793856491476, 2597.5584150694426, 2603.934901627465, 2604.0625374865035, 2581.819272650915, 2587.288495663698, 2608.569586018314, 2614.5685961816275, 2614.830036047651, 2580.6516120323704, 2582.496413199427, 2611.138324352978, 2626.0, 2627.4482358704695, 9185.357025008252, 9200.217981471584, 9213.953930230373, 9236.399634023766, 9250.907490925072, 5783.768649254025, 5784.82645520635, 5866.2960444746905, 5958.493528050121, 5959.731812068468, 3647.2340808430263, 3755.7183211259266, 3793.3551296279697, 3800.3539398302355, 3805.0, 792.579932635704, 1034.8085604163255, 1060.4538662588773, 1076.4671487613602, 1097.226455348977, 12397.62807115788, 12436.880680738686, 12500.937420319284, 12505.74988500225, 12522.749545009048, 4683.805629564507, 4721.480637129913, 4964.90070198594, 5049.798008079636, 5134.7946082156295, 10515.211359148094, 10609.0, 10617.097546708463, 10629.84055239172, 10645.627403040857, 5516.924199781004, 5774.162746401748, 5796.62321949072, 5798.449147330997, 5894.941050589525, 4694.741789201558, 4700.412448443944, 5088.440271570124, 5135.640505164677, 5181.948180518147, 3762.1535154590547, 4077.734947228421, 4086.6526345260986, 4148.128892932457, 4194.790260486963, 1142.5601143559788, 1377.9104358216684, 1383.3429121167567, 1388.590365842079, 1395.0792476965155, 1363.6863521390135, 1366.549038817188, 1375.9793603095961, 1411.9505817296345, 1434.211183848879, 8345.873307103464, 8369.58152092393, 8480.660773569112, 8515.829683406297, 8540.487570745776, 525.9842204733864, 531.7607076815449, 534.5402953460022, 535.6705626039958, 536.4822945857188, 6336.714847831859, 6338.714757835908, 6409.102725618417, 6450.322716114834, 6474.255460621995, 10743.677689669323, 11060.446977651085, 11089.27919685218, 11145.944270278596, 11158.776824463628, 10633.627823026312, 10925.289856159325, 11031.61389351385, 11068.778624427467, 11150.888491115147, 8563.015253245832, 8574.013988391414, 8583.65665373378, 8601.397902146915, 8626.223639872316, 8003.59982000886, 8087.353011759163, 8131.146229645802, 8324.958375208085, 8357.832843343222, 8022.438429309892, 9478.857817132886, 9648.179904708179, 9746.415215087118, 9788.265880059167, 9792.51037448139, 10057.949710251694, 10104.292699510963, 10145.746356341151, 10246.026627470379, 6948.930510694829, 7691.538507689333, 7711.305982461606, 7759.379249660126, 7814.648340824563, 8376.371772117238, 8455.534945578052, 8530.14698530142, 8543.700970466003, 8590.1839325264, 7360.852782944418, 7527.962360188164, 7563.886541701769, 7637.389008879165, 7644.967929329692, 7408.296211859864, 7445.739399121005, 7488.138864030706, 7517.097948246245, 7576.090869095744, 7907.2488113629115, 7911.564863932539, 7920.603969801602, 7982.640781164978, 7983.760487185394, 7106.502544822019, 7248.238934911758, 7256.7822965311125, 7312.817179570553, 7381.335679788845, 7384.778456646309, 7468.925310746823, 7479.70081196746, 7548.433867460072, 7568.318851303409], "unit": "score", "name": "mootools.html", "value": 5023.831496560081}, {"lowerIsBetter": false, "replicates": [336.6599734268388, 337.2462546209222, 342.1514643683666, 345.319720151302, 350.7982909826849, 6937.653117344132, 6970.7560235391775, 6994.0, 7087.85824283514, 7088.113985751781, 5541.362743284523, 6896.48276379271, 6968.512204145712, 6989.580625162494, 7013.719451221945, 4870.123377791996, 4982.825601103962, 5098.337216161894, 5176.404713457944, 5181.792728290864, 4170.311898536738, 5148.510891465305, 5164.122099243119, 5172.793088276464, 5200.453952335007, 4598.931016034762, 4699.90600187996, 5110.923336149961, 5168.198929165967, 5168.689878607277, 5139.100657384943, 5153.665011774223, 5153.716545589991, 5154.92267615986, 5155.4586768389345, 4402.163588918127, 4430.468343798732, 4435.201663700533, 4438.112377524508, 4447.73313601185, 5507.917381239285, 6406.583572067801, 6531.771388001444, 6758.9324106758795, 6769.559978601375, 5322.627416080876, 5967.403259673998, 5989.490893274077, 6022.488088512482, 6056.394360563952, 7383.150937642186, 7396.889046664304, 7397.371223446014, 7406.8888966665545, 7428.925710742931, 4900.901981960341, 4908.877278068041, 4919.631027672903, 4921.212605983062, 4949.678270912379, 7609.695612175562, 7888.56612886291, 7889.1716369780925, 7890.0926393464915, 7904.20957904205, 4984.22744474607, 5033.194688849767, 5034.967831594588, 5044.394672639233, 5044.974775126101, 5085.64400491962, 5131.589472842164, 5138.820141295037, 5149.794008239628, 5150.819721309811, 5029.974850125726, 5829.912551311733, 6014.548908831855, 6023.27720673522, 6037.366076561985, 7461.291177338144, 7468.85062298751, 7496.662650180755, 7499.62501874915, 7505.061867266591, 1781.2963879267875, 1790.4539115569617, 1792.829681180286, 1793.560577658456, 1793.8116497767808, 1055.472263868066, 1083.951222195003, 1095.2935356694886, 1095.4303762043692, 1097.4074000040048, 6773.593584384953, 6882.7591034313555, 6895.379415852597, 6911.7926462206215, 6928.44572434204, 6036.426539478743, 7192.172900116449, 7690.2694244046725, 7693.269139431745, 7706.152323244439, 6830.590164590041, 6910.689018994158, 6918.446524278046, 6948.513604047668, 6949.478789090838, 4038.0510580013674, 4080.449139366207, 4081.3265811140827, 4095.4471146394853, 4126.216018956388, 5901.940980590139, 7568.735094271674, 8130.227628375294, 8142.267195952392, 8148.185181481923, 5856.0, 6817.795466136024, 6970.930290697028, 7005.964970175117, 7388.963055184797, 6407.647579383179, 7411.888821667571, 8198.713045043514, 8204.220599042961, 8227.382946278933, 6418.93581064202, 7706.113796913202, 7835.177306382748, 7840.255175758294, 7860.135385107519, 5696.344920334214, 6613.875641141114, 7232.819179520344, 7659.348955338635, 7667.0, 7897.565633890191, 8111.837763244584, 8161.959190204011, 8174.591270436574, 8214.63034163476, 4837.419509658864, 4848.248521479177, 4898.951010489849, 4901.14230009754, 4901.656884018084, 7759.883601746092, 7762.379009679099, 7774.455788094779, 7779.649915753692, 7807.531548107132, 7467.775966721007, 7469.365103966006, 7475.476716629783, 7476.775696729107, 7477.364424024019, 7252.121289902166, 7370.189279179393, 7472.14070381913, 7477.4765766398, 7484.663190156565, 5950.34546199909, 6902.930970690229, 7253.3834624057545, 7263.0, 7306.050213472215, 7473.551586904803, 7478.4765066447, 7479.513831600929, 7481.952526646165, 7487.850242995, 7288.708451661874, 7292.489525733148, 7294.927050729637, 7315.378192853667, 7315.805258422559], "unit": "score", "name": "prototype.html", "value": 5379.176600570666}, {"lowerIsBetter": false, "replicates": [669.4978765925556, 715.926378904036, 727.138341065837, 735.2500449541466, 749.4229443328637, 15412.845871541282, 15573.299201535943, 15741.685166296667, 15828.208589570519, 15841.28714207862, 13319.33403329832, 14673.706525869477, 14697.55907322782, 14731.558053258392, 14779.187144707037, 964.1852634523816, 966.9903300967006, 971.0192705367559, 978.041519311072, 980.2648013989508, 960.6973803251988, 967.4533888353093, 970.4419958523877, 974.780674348273, 975.7024107647164, 1008.4755926917999, 1012.3217444312328, 1019.9592016319339, 1024.6311327921942, 1025.2310766924807, 987.8172538080478, 998.1166667499243, 1009.0313299232708, 1010.9241806864441, 1014.2646581228587, 760.2701406649624, 762.3329586611715, 764.9885251721229, 765.1392183793232, 766.8389638175989, 13787.931060344634, 13974.860251397458, 14011.229382383965, 14037.22795246261, 14055.578332650037, 11790.410479475991, 11801.055915526736, 11810.0, 11870.643880683507, 11917.523299068114, 9525.714228573153, 9612.807743845155, 9641.228701703847, 9834.754131146707, 9975.102240798364, 3390.7117894978956, 3449.741269404805, 3466.861325546975, 3507.386207413718, 3551.3430015447225, 59280.81438371252, 72430.0, 75164.24835751628, 79746.0, 86333.56833215794, 9524.523773811281, 9809.607615695435, 9885.950570247103, 9929.5317107302, 10711.625093121704, 21929.561408771737, 22132.66800997986, 22229.444263893532, 22233.0, 22241.888790555942, 6502.642354670444, 7498.700051997859, 7530.171681115073, 7613.9238607614325, 7659.387249020065, 4179.895502612459, 4180.623743863067, 4244.660427165819, 4309.612134907873, 4317.611414972667, 3708.2212735325884, 3723.255348930225, 3737.364648009845, 3738.2897249522503, 3766.6986641068647, 674.140470899607, 681.9215790184093, 688.0711040095831, 693.2166651683666, 696.1646024770296, 595.4849055566904, 601.5007543738686, 607.7083000159948, 608.9908651370233, 611.4802417944711, 8466.365022623328, 8610.827783444296, 8616.827663446697, 8723.389362744547, 8868.290536757173, 515.1268599723433, 567.8864227154503, 577.408156639448, 594.3551246897165, 642.3929386729495, 3346.748993825473, 3874.8256328465286, 3919.7452165609143, 3972.82122304497, 4001.579834117434, 99688.50155749175, 99944.50083248757, 101051.0, 101907.0, 102060.0, 74548.88176677354, 74965.25034749691, 79456.20543794488, 86080.56959715161, 86155.27689446176, 68937.96593051108, 69471.30528694749, 70301.2969870305, 70404.0, 70624.94062588962, 6295.527835412271, 8676.305895528469, 8731.869021964803, 8739.0, 8927.642894284156, 50120.74939625279, 51449.228261576856, 52563.737181313845, 53010.0, 53449.198262025326, 70663.64668176626, 74175.62912185404, 74337.62831185809, 75271.87092193731, 75282.62358688172, 10981.17641176931, 11169.832452513381, 11216.495257713259, 11490.08079353633, 11701.414929253333, 11699.0, 11880.980233360176, 12164.635060948185, 12412.255264684149, 12976.610701678965, 11713.648590542298, 11741.706457338294, 11773.234739741889, 11908.523659053888, 12240.632781016584, 11814.940925295663, 11823.52705891789, 11842.46708898119, 11968.820467693165, 12002.579909703118, 11644.41777911118, 11655.708607284885, 11693.356865372487, 11707.356095414834, 11803.645890623295, 9752.804943900941, 9824.705258842247, 9900.75248118774, 9957.651482198089, 10233.130183934449, 9839.803203936024, 9967.053129952643, 9993.900060999587, 10079.34484258521, 10105.848412273675], "unit": "score", "name": "yui.html", "value": 7092.136705284587}], "name": "dromaeo_css", "value": 7731.170073408427}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [115.0, 110.0, 114.0, 113.0, 114.0, 130.0, 117.0, 115.0, 115.0, 116.0], "unit": "score", "name": "ai-astar", "value": 115.9}, {"lowerIsBetter": true, "replicates": [148.0, 120.0, 140.0, 158.0, 115.0, 142.0, 138.0, 120.0, 155.0, 121.0], "unit": "score", "name": "audio-beat-detection", "value": 135.7}, {"lowerIsBetter": true, "replicates": [191.0, 193.0, 194.0, 194.0, 195.0, 200.0, 197.0, 201.0, 203.0, 201.0], "unit": "score", "name": "audio-dft", "value": 196.9}, {"lowerIsBetter": true, "replicates": [69.0, 79.0, 71.0, 112.0, 59.0, 65.0, 83.0, 86.0, 160.0, 62.0], "unit": "score", "name": "audio-fft", "value": 84.6}, {"lowerIsBetter": true, "replicates": [87.0, 80.0, 160.0, 116.0, 86.0, 82.0, 119.0, 84.0, 80.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 101.3}, {"lowerIsBetter": true, "replicates": [136.0, 130.0, 94.0, 88.0, 91.0, 87.0, 91.0, 92.0, 128.0, 87.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 102.4}, {"lowerIsBetter": true, "replicates": [151.0, 104.0, 149.0, 152.0, 92.0, 152.0, 149.0, 95.0, 95.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 123.4}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 92.0, 93.0, 113.0, 122.0, 104.0, 85.0, 135.0, 116.0], "unit": "score", "name": "imaging-desaturate", "value": 104.6}, {"lowerIsBetter": true, "replicates": [55.0, 61.0, 60.0, 59.0, 59.0, 60.0, 79.0, 58.0, 49.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 59.3}, {"lowerIsBetter": true, "replicates": [62.0, 66.0, 67.0, 71.0, 65.0, 64.0, 75.0, 97.0, 97.0, 97.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 76.1}, {"lowerIsBetter": true, "replicates": [81.0, 83.0, 82.0, 85.0, 80.0, 82.0, 81.0, 84.0, 81.0, 90.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.9}, {"lowerIsBetter": true, "replicates": [137.0, 138.0, 136.0, 120.0, 126.0, 122.0, 130.0, 122.0, 153.0, 134.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 131.8}, {"lowerIsBetter": true, "replicates": [125.0, 136.0, 138.0, 144.0, 180.0, 174.0, 136.0, 133.0, 163.0, 171.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 150.0}, {"lowerIsBetter": true, "replicates": [65.0, 98.0, 90.0, 99.0, 55.0, 66.0, 93.0, 93.0, 97.0, 99.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 85.5}], "name": "kraken", "value": 1550.3999999999999}]} 03:08:48 INFO - Return code: 0 03:08:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:08:48 ERROR - # TBPL SUCCESS # 03:08:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:08:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:08:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:08:48 INFO - 2016-04-17 03:08:48.437 screenresolution[1982:18776] starting screenresolution argv=screenresolution get 03:08:48 INFO - 2016-04-17 03:08:48.440 screenresolution[1982:18776] Display 0: 1600x1200x32@60 03:08:48 INFO - 2016-04-17 03:08:48.446 screenresolution[1983:18779] starting screenresolution argv=screenresolution list 03:08:48 INFO - Available Modes on Display 0 03:08:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:08:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:08:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:08:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:08:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:08:48 INFO - Intel Iris: 03:08:48 INFO - Chipset Model: Intel Iris 03:08:48 INFO - Type: GPU 03:08:48 INFO - Bus: Built-In 03:08:48 INFO - VRAM (Dynamic, Max): 1536 MB 03:08:48 INFO - Vendor: Intel (0x8086) 03:08:48 INFO - Device ID: 0x0a2e 03:08:48 INFO - Revision ID: 0x0009 03:08:48 INFO - Displays: 03:08:48 INFO - Display: 03:08:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:08:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:08:48 INFO - Display Serial Number: 1600x1200 60 03:08:48 INFO - Main Display: Yes 03:08:48 INFO - Mirror: Off 03:08:48 INFO - Online: Yes 03:08:48 INFO - Rotation: Supported 03:08:48 INFO - Return code: 0 03:08:48 INFO - Running post-action listener: _resource_record_post_action 03:08:48 INFO - Running post-run listener: _resource_record_post_run 03:08:49 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 708608; Write bytes: 668111360; Read time: 9; Write time: 1906 03:08:49 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 351756800; Write bytes: 353536000; Read time: 27966; Write time: 671 03:08:49 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 708608; Write bytes: 303929856; Read time: 9; Write time: 1195 03:08:49 INFO - Running post-run listener: _upload_blobber_files 03:08:49 INFO - Blob upload gear active. 03:08:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:08:49 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 03:08:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:08:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:08:50 INFO - (blobuploader) - INFO - Open directory for files ... 03:08:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:08:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:08:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:51 INFO - (blobuploader) - INFO - Done attempting. 03:08:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:08:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:08:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:52 INFO - (blobuploader) - INFO - Done attempting. 03:08:52 INFO - (blobuploader) - INFO - Iteration through files over. 03:08:52 INFO - Return code: 0 03:08:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:08:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:08:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2442403a4a6ecb59c23e4b90883665e5643b460a0400efdcc77f9589a4ffc747aa9f0ee0a35af50030109953126c11a6be12fdb1c90574ff7d8351121a3812b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f72131f388d4be41dee37493a49ff48b8d5674a6dbbf48c0d47c4900386fba094ca923bc701eb447abc17fbb6a4c32193869a17b14f56f56e0be3134d90caa52"} 03:08:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:08:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:08:52 INFO - Contents: 03:08:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2442403a4a6ecb59c23e4b90883665e5643b460a0400efdcc77f9589a4ffc747aa9f0ee0a35af50030109953126c11a6be12fdb1c90574ff7d8351121a3812b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f72131f388d4be41dee37493a49ff48b8d5674a6dbbf48c0d47c4900386fba094ca923bc701eb447abc17fbb6a4c32193869a17b14f56f56e0be3134d90caa52"} 03:08:52 INFO - Running post-run listener: copy_logs_to_upload_dir 03:08:52 INFO - Copying logs to upload dir... 03:08:52 INFO - mkdir: /builds/slave/test/build/upload/logs 03:08:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.284254 ========= master_lag: 2.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-04-17 03:08:54.342707) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-17 03:08:54.345996) ========= 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.glFmtWhVtG/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.gC7MgLOBNC/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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/Mozilla-Aurora/sha512/c2442403a4a6ecb59c23e4b90883665e5643b460a0400efdcc77f9589a4ffc747aa9f0ee0a35af50030109953126c11a6be12fdb1c90574ff7d8351121a3812b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f72131f388d4be41dee37493a49ff48b8d5674a6dbbf48c0d47c4900386fba094ca923bc701eb447abc17fbb6a4c32193869a17b14f56f56e0be3134d90caa52"} build_url:https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010989 build_url: 'https://queue.taskcluster.net/v1/task/IYEV4MVyTsyL02W3eC00zQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2442403a4a6ecb59c23e4b90883665e5643b460a0400efdcc77f9589a4ffc747aa9f0ee0a35af50030109953126c11a6be12fdb1c90574ff7d8351121a3812b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f72131f388d4be41dee37493a49ff48b8d5674a6dbbf48c0d47c4900386fba094ca923bc701eb447abc17fbb6a4c32193869a17b14f56f56e0be3134d90caa52"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-17 03:08:54.377010) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 03:08:54.377290) ========= 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.glFmtWhVtG/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.gC7MgLOBNC/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.005084 ========= master_lag: 0.14 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 03:08:54.525763) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-17 03:08:54.526465) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-17 03:08:54.528261) ========= ========= Total master_lag: 2.55 =========