builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1490526822.64 results: success (0) buildid: 20170326030204 builduid: 85086df7eb6740fa8e01304b84f890ec revision: f5e214144799889e2408c4841351f4053f00544e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:42.643325) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:42.643798) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:42.644104) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:42.837189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:42.837463) ========= 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.JmcZWozinP/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.MMyUEE3rDr/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 --2017-03-26 04:13:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.8M=0.001s 2017-03-26 04:13:43 (12.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.738240 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:43.600212) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:43.600547) ========= 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.JmcZWozinP/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.MMyUEE3rDr/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.029530 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:43.651683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-26 04:13:43.652046) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f5e214144799889e2408c4841351f4053f00544e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f5e214144799889e2408c4841351f4053f00544e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JmcZWozinP/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.MMyUEE3rDr/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 2017-03-26 04:13:43,779 truncating revision to first 12 chars 2017-03-26 04:13:43,779 Setting DEBUG logging. 2017-03-26 04:13:43,779 attempt 1/10 2017-03-26 04:13:43,779 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f5e214144799?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-26 04:13:44,607 unpacking tar archive at: mozilla-central-f5e214144799/testing/mozharness/ program finished with exit code 0 elapsedTime=1.101926 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-26 04:13:44.777809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:44.778144) ========= script_repo_revision: f5e214144799889e2408c4841351f4053f00544e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:44.778540) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:44.778834) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 04:13:44.798504) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2017-03-26 04:13:44.798803) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JmcZWozinP/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.MMyUEE3rDr/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:13:45 INFO - MultiFileLogger online at 20170326 04:13:45 in /builds/slave/test 04:13:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:13:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:13:45 INFO - {'append_to_log': False, 04:13:45 INFO - 'base_work_dir': '/builds/slave/test', 04:13:45 INFO - 'blob_upload_branch': 'Firefox', 04:13:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:13:45 INFO - 'branch': 'Firefox', 04:13:45 INFO - 'buildbot_json_path': 'buildprops.json', 04:13:45 INFO - 'config_files': ('talos/mac_config.py',), 04:13:45 INFO - 'default_actions': ('clobber', 04:13:45 INFO - 'read-buildbot-config', 04:13:45 INFO - 'download-and-extract', 04:13:45 INFO - 'populate-webroot', 04:13:45 INFO - 'create-virtualenv', 04:13:45 INFO - 'install', 04:13:45 INFO - 'run-tests'), 04:13:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:13:45 INFO - 'download_minidump_stackwalk': True, 04:13:45 INFO - 'download_symbols': 'ondemand', 04:13:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:13:45 INFO - 'tooltool.py': '/tools/tooltool.py', 04:13:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:13:45 INFO - '/tools/misc-python/virtualenv.py')}, 04:13:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:13:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:13:45 INFO - 'gecko_profile': False, 04:13:45 INFO - 'gecko_profile_interval': 0, 04:13:45 INFO - 'installer_path': 'installer.exe', 04:13:45 INFO - 'log_level': 'info', 04:13:45 INFO - 'log_name': 'talos', 04:13:45 INFO - 'log_to_console': True, 04:13:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:13:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:13:45 INFO - 'opt_config_files': (), 04:13:45 INFO - 'pip_index': False, 04:13:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:13:45 INFO - 'cmd': ('bash', 04:13:45 INFO - '-c', 04:13:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:13:45 INFO - 'enabled': True, 04:13:45 INFO - 'halt_on_failure': False, 04:13:45 INFO - 'name': 'check_screen_resolution'},), 04:13:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:13:45 INFO - 'cmd': ('bash', 04:13:45 INFO - '-c', 04:13:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:13:45 INFO - 'enabled': True, 04:13:45 INFO - 'halt_on_failure': False, 04:13:45 INFO - 'name': 'check_screen_resolution'},), 04:13:45 INFO - 'run_cmd_checks_enabled': True, 04:13:45 INFO - 'suite': 'dromaeojs', 04:13:45 INFO - 'system_bits': '32', 04:13:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:13:45 INFO - 'title': 't-yosemite-r7-0110', 04:13:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:13:45 INFO - 'use_talos_json': True, 04:13:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:13:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:13:45 INFO - 'work_dir': 'build'} 04:13:45 INFO - [mozharness: 2017-03-26 11:13:45.040295Z] Running clobber step. 04:13:45 INFO - Running pre-action listener: _resource_record_pre_action 04:13:45 INFO - Running main action method: clobber 04:13:45 INFO - rmtree: /builds/slave/test/build 04:13:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:13:47 INFO - Running post-action listener: _resource_record_post_action 04:13:47 INFO - [mozharness: 2017-03-26 11:13:47.755132Z] Finished clobber step (success) 04:13:47 INFO - [mozharness: 2017-03-26 11:13:47.755260Z] Running read-buildbot-config step. 04:13:47 INFO - Running pre-action listener: _resource_record_pre_action 04:13:47 INFO - Running main action method: read_buildbot_config 04:13:47 INFO - Using buildbot properties: 04:13:47 INFO - { 04:13:47 INFO - "project": "", 04:13:47 INFO - "product": "firefox", 04:13:47 INFO - "script_repo_revision": "production", 04:13:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:13:47 INFO - "repository": "", 04:13:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:13:47 INFO - "buildid": "20170326030204", 04:13:47 INFO - "stage_platform": "macosx64", 04:13:47 INFO - "basedir": "/builds/slave/test", 04:13:47 INFO - "nightly_build": "True", 04:13:47 INFO - "buildnumber": 36, 04:13:47 INFO - "slavename": "t-yosemite-r7-0110", 04:13:47 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:13:47 INFO - "platform": "yosemite_r7", 04:13:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:13:47 INFO - "branch": "mozilla-central", 04:13:47 INFO - "repo_path": "mozilla-central", 04:13:47 INFO - "revision": "f5e214144799889e2408c4841351f4053f00544e", 04:13:47 INFO - "pgo_build": "False", 04:13:47 INFO - "builduid": "85086df7eb6740fa8e01304b84f890ec", 04:13:47 INFO - "slavebuilddir": "test" 04:13:47 INFO - } 04:13:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:13:47 INFO - Running post-action listener: _resource_record_post_action 04:13:47 INFO - [mozharness: 2017-03-26 11:13:47.758736Z] Finished read-buildbot-config step (success) 04:13:47 INFO - [mozharness: 2017-03-26 11:13:47.758868Z] Running download-and-extract step. 04:13:47 INFO - Running pre-action listener: _resource_record_pre_action 04:13:47 INFO - Running main action method: download_and_extract 04:13:47 INFO - mkdir: /builds/slave/test/build/tests 04:13:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:47 INFO - trying https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:13:47 INFO - Downloading https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:13:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:13:49 INFO - Downloaded 1325 bytes. 04:13:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:13:49 INFO - Using the following test package requirements: 04:13:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:13:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:13:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:13:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:13:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'jsshell-mac.zip'], 04:13:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:13:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:13:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:13:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:13:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:13:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:13:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:13:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:13:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:13:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:13:49 INFO - Fetch https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:13:51 INFO - Expected file size: 52411571 04:13:51 INFO - Obtained file size: 52411571 04:14:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:14:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:14:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:14:07 INFO - Fetch https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:14:09 INFO - Expected file size: 52411571 04:14:09 INFO - Obtained file size: 52411571 04:14:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:14:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:14:25 INFO - Fetch https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:14:26 INFO - Expected file size: 14404171 04:14:26 INFO - Obtained file size: 14404171 04:14:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:26 INFO - trying https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:14:26 INFO - Downloading https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:14:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:14:29 INFO - Downloaded 75668146 bytes. 04:14:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:14:29 INFO - mkdir: /builds/slave/test/properties 04:14:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:14:29 INFO - Writing to file /builds/slave/test/properties/build_url 04:14:29 INFO - Contents: 04:14:29 INFO - build_url:https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:14:30 INFO - Running post-action listener: _resource_record_post_action 04:14:30 INFO - Running post-action listener: set_extra_try_arguments 04:14:30 INFO - [mozharness: 2017-03-26 11:14:30.565297Z] Finished download-and-extract step (success) 04:14:30 INFO - [mozharness: 2017-03-26 11:14:30.565423Z] Running populate-webroot step. 04:14:30 INFO - Running pre-action listener: _resource_record_pre_action 04:14:30 INFO - Running main action method: populate_webroot 04:14:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:14:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:14:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:14:30 INFO - '--tppagecycles', 04:14:30 INFO - '7'], 04:14:30 INFO - 'tests': ['tsvgm']}}, 04:14:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:14:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:14:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:14:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:14:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:14:30 INFO - 'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:14:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:14:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:14:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:14:30 INFO - 'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['damp', 'tps']}, 04:14:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:14:30 INFO - 'tests': ['damp', 'tps']}, 04:14:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['dromaeo_dom']}, 04:14:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:14:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['basic_compositor_video']}, 04:14:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:14:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['a11yr', 04:14:30 INFO - 'ts_paint', 04:14:30 INFO - 'tpaint', 04:14:30 INFO - 'sessionrestore', 04:14:30 INFO - 'sessionrestore_no_auto_restore', 04:14:30 INFO - 'tabpaint']}, 04:14:30 INFO - 'other-e10s': {'tests': ['a11yr', 04:14:30 INFO - 'ts_paint', 04:14:30 INFO - 'tpaint', 04:14:30 INFO - 'sessionrestore', 04:14:30 INFO - 'sessionrestore_no_auto_restore', 04:14:30 INFO - 'tabpaint']}, 04:14:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:14:30 INFO - 'ts_paint', 04:14:30 INFO - 'tpaint', 04:14:30 INFO - 'sessionrestore', 04:14:30 INFO - 'sessionrestore_no_auto_restore', 04:14:30 INFO - 'tabpaint']}, 04:14:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:14:30 INFO - 'ts_paint', 04:14:30 INFO - 'tpaint', 04:14:30 INFO - 'sessionrestore', 04:14:30 INFO - 'sessionrestore_no_auto_restore', 04:14:30 INFO - 'tabpaint']}, 04:14:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['a11yr', 04:14:30 INFO - 'ts_paint', 04:14:30 INFO - 'tpaint', 04:14:30 INFO - 'sessionrestore', 04:14:30 INFO - 'sessionrestore_no_auto_restore', 04:14:30 INFO - 'tabpaint']}, 04:14:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['a11yr', 04:14:30 INFO - 'ts_paint', 04:14:30 INFO - 'tpaint', 04:14:30 INFO - 'sessionrestore', 04:14:30 INFO - 'sessionrestore_no_auto_restore', 04:14:30 INFO - 'tabpaint']}, 04:14:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['tsvgx', 04:14:30 INFO - 'tsvgr_opacity', 04:14:30 INFO - 'tart', 04:14:30 INFO - 'tscrollx', 04:14:30 INFO - 'cart', 04:14:30 INFO - 'tsvg_static']}, 04:14:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:14:30 INFO - 'tsvgr_opacity', 04:14:30 INFO - 'tart', 04:14:30 INFO - 'tscrollx', 04:14:30 INFO - 'cart', 04:14:30 INFO - 'tsvg_static']}, 04:14:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:14:30 INFO - 'talos_options': ['--disable-e10s'], 04:14:30 INFO - 'tests': ['tp5o']}, 04:14:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:14:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:14:30 INFO - 'talos_options': ['--disable-e10s', 04:14:30 INFO - '--xperf_path', 04:14:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:14:30 INFO - 'tests': ['tp5n']}, 04:14:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:14:30 INFO - 'talos_options': ['--xperf_path', 04:14:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:14:30 INFO - 'tests': ['tp5n']}}, 04:14:30 INFO - 'talos.zip': {'path': '', 04:14:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:14:30 INFO - Running post-action listener: _resource_record_post_action 04:14:30 INFO - [mozharness: 2017-03-26 11:14:30.583451Z] Finished populate-webroot step (success) 04:14:30 INFO - [mozharness: 2017-03-26 11:14:30.583555Z] Running create-virtualenv step. 04:14:30 INFO - Running pre-action listener: _resource_record_pre_action 04:14:30 INFO - Running main action method: create_virtualenv 04:14:30 INFO - Creating virtualenv /builds/slave/test/build/venv 04:14:30 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 04:14:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:14:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:14:30 INFO - Using real prefix '/tools/python27' 04:14:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:14:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:14:34 INFO - Installing pip.................done. 04:14:34 INFO - Return code: 0 04:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:14:34 INFO - Reading from file tmpfile_stdout 04:14:34 INFO - Output received: 04:14:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:14:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:34 INFO - 'HOME': '/Users/cltbld', 04:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:34 INFO - 'LOGNAME': 'cltbld', 04:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:34 INFO - 'MOZ_NO_REMOTE': '1', 04:14:34 INFO - 'NO_EM_RESTART': '1', 04:14:34 INFO - 'PAGER': '/bin/cat', 04:14:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:34 INFO - 'PWD': '/builds/slave/test', 04:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:34 INFO - 'SHELL': '/bin/bash', 04:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:14:34 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:34 INFO - 'USER': 'cltbld', 04:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:34 INFO - 'XPC_FLAGS': '0x0', 04:14:34 INFO - 'XPC_SERVICE_NAME': '0', 04:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:34 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 04:14:34 INFO - Cleaning up... 04:14:34 INFO - Return code: 0 04:14:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:34 INFO - 'HOME': '/Users/cltbld', 04:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:34 INFO - 'LOGNAME': 'cltbld', 04:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:34 INFO - 'MOZ_NO_REMOTE': '1', 04:14:34 INFO - 'NO_EM_RESTART': '1', 04:14:34 INFO - 'PAGER': '/bin/cat', 04:14:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:34 INFO - 'PWD': '/builds/slave/test', 04:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:34 INFO - 'SHELL': '/bin/bash', 04:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:14:34 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:34 INFO - 'USER': 'cltbld', 04:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:34 INFO - 'XPC_FLAGS': '0x0', 04:14:34 INFO - 'XPC_SERVICE_NAME': '0', 04:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:35 INFO - Downloading/unpacking psutil>=3.1.1 04:14: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 04:14: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 04:14: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 04:14: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 04:14: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 04:14: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 04:14:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:14:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:14:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:14:39 INFO - Installing collected packages: psutil 04:14:39 INFO - Running setup.py install for psutil 04:14:39 INFO - building 'psutil._psutil_osx' extension 04:14:39 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 04:14:39 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 04:14:39 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 04:14:40 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 04:14:40 INFO - building 'psutil._psutil_posix' extension 04:14:40 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 04:14:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:14:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:14:40 INFO - ^ 04:14:40 INFO - 1 warning generated. 04:14:40 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 04:14:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:14:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:14:40 INFO - Successfully installed psutil 04:14:40 INFO - Cleaning up... 04:14:40 INFO - Return code: 0 04:14:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:14:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:14:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:14:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:14:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:14:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:40 INFO - 'HOME': '/Users/cltbld', 04:14:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:40 INFO - 'LOGNAME': 'cltbld', 04:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:40 INFO - 'MOZ_NO_REMOTE': '1', 04:14:40 INFO - 'NO_EM_RESTART': '1', 04:14:40 INFO - 'PAGER': '/bin/cat', 04:14:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:40 INFO - 'PWD': '/builds/slave/test', 04:14:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:40 INFO - 'SHELL': '/bin/bash', 04:14:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:14:40 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:14:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:40 INFO - 'USER': 'cltbld', 04:14:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:40 INFO - 'XPC_FLAGS': '0x0', 04:14:40 INFO - 'XPC_SERVICE_NAME': '0', 04:14:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:14:40 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 04:14:40 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 04:14:40 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 04:14:40 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 04:14:40 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 04:14:40 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 04:14:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:14:43 INFO - Installing collected packages: mozsystemmonitor 04:14:43 INFO - Running setup.py install for mozsystemmonitor 04:14:43 INFO - Successfully installed mozsystemmonitor 04:14:43 INFO - Cleaning up... 04:14:43 INFO - Return code: 0 04:14:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:14:43 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:43 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:43 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:14:43 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==2.5.1'] in /builds/slave/test/build 04:14:43 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==2.5.1 04:14:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:14:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:43 INFO - 'HOME': '/Users/cltbld', 04:14:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:43 INFO - 'LOGNAME': 'cltbld', 04:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:43 INFO - 'MOZ_NO_REMOTE': '1', 04:14:43 INFO - 'NO_EM_RESTART': '1', 04:14:43 INFO - 'PAGER': '/bin/cat', 04:14:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:43 INFO - 'PWD': '/builds/slave/test', 04:14:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:43 INFO - 'SHELL': '/bin/bash', 04:14:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:14:43 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:14:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:43 INFO - 'USER': 'cltbld', 04:14:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:43 INFO - 'XPC_FLAGS': '0x0', 04:14:43 INFO - 'XPC_SERVICE_NAME': '0', 04:14:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:44 INFO - Downloading/unpacking jsonschema==2.5.1 04:14:44 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 04:14:44 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 04:14:44 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 04:14:44 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 04:14:44 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 04:14:44 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 04:14:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:14:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:47 INFO - Downloading functools32-3.2.3-2.tar.gz 04:14:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:14:47 INFO - warning: no files found matching '*.txt' 04:14:47 INFO - no previously-included directories found matching 'build' 04:14:47 INFO - no previously-included directories found matching 'dist' 04:14:47 INFO - no previously-included directories found matching '.git*' 04:14:47 INFO - Installing collected packages: jsonschema, functools32 04:14:47 INFO - Running setup.py install for functools32 04:14:47 INFO - warning: no files found matching '*.txt' 04:14:47 INFO - no previously-included directories found matching 'build' 04:14:47 INFO - no previously-included directories found matching 'dist' 04:14:47 INFO - no previously-included directories found matching '.git*' 04:14:47 INFO - Successfully installed jsonschema functools32 04:14:47 INFO - Cleaning up... 04:14:47 INFO - Return code: 0 04:14:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:14:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:47 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:14:47 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:14:47 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 functools32==3.2.3-2 04:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:47 INFO - 'HOME': '/Users/cltbld', 04:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:47 INFO - 'LOGNAME': 'cltbld', 04:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:47 INFO - 'MOZ_NO_REMOTE': '1', 04:14:47 INFO - 'NO_EM_RESTART': '1', 04:14:47 INFO - 'PAGER': '/bin/cat', 04:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:47 INFO - 'PWD': '/builds/slave/test', 04:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:47 INFO - 'SHELL': '/bin/bash', 04:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:14:47 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:47 INFO - 'USER': 'cltbld', 04:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:47 INFO - 'XPC_FLAGS': '0x0', 04:14:47 INFO - 'XPC_SERVICE_NAME': '0', 04:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:14:47 INFO - Cleaning up... 04:14:47 INFO - Return code: 0 04:14:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:14:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:47 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 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:14:47 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 04:14:47 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 04:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:47 INFO - 'HOME': '/Users/cltbld', 04:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:47 INFO - 'LOGNAME': 'cltbld', 04:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:47 INFO - 'MOZ_NO_REMOTE': '1', 04:14:47 INFO - 'NO_EM_RESTART': '1', 04:14:47 INFO - 'PAGER': '/bin/cat', 04:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:47 INFO - 'PWD': '/builds/slave/test', 04:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:47 INFO - 'SHELL': '/bin/bash', 04:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:14:47 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:47 INFO - 'USER': 'cltbld', 04:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:47 INFO - 'XPC_FLAGS': '0x0', 04:14:47 INFO - 'XPC_SERVICE_NAME': '0', 04:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:48 INFO - Downloading/unpacking blobuploader==1.2.4 04:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:50 INFO - Downloading blobuploader-1.2.4.tar.gz 04:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:14:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:14:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:52 INFO - Downloading docopt-0.6.1.tar.gz 04:14:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:14:52 INFO - Installing collected packages: blobuploader, requests, docopt 04:14:52 INFO - Running setup.py install for blobuploader 04:14:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:14:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:14:52 INFO - Running setup.py install for requests 04:14:52 INFO - Running setup.py install for docopt 04:14:52 INFO - Successfully installed blobuploader requests docopt 04:14:52 INFO - Cleaning up... 04:14:53 INFO - Return code: 0 04:14:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:53 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 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:14:53 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 04:14:53 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 04:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:53 INFO - 'HOME': '/Users/cltbld', 04:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:53 INFO - 'LOGNAME': 'cltbld', 04:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:53 INFO - 'MOZ_NO_REMOTE': '1', 04:14:53 INFO - 'NO_EM_RESTART': '1', 04:14:53 INFO - 'PAGER': '/bin/cat', 04:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:53 INFO - 'PWD': '/builds/slave/test', 04:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:53 INFO - 'SHELL': '/bin/bash', 04:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:14:53 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:53 INFO - 'USER': 'cltbld', 04:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:53 INFO - 'XPC_FLAGS': '0x0', 04:14:53 INFO - 'XPC_SERVICE_NAME': '0', 04:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:14:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_uYBR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:14:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-vBtlvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:14:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-HHPvcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:14:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-X0EmMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:14:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-vN1De9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:14:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-5v0nAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:14:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-oR9gjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-z4uwDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6PPyts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-dS8oEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-CVuqZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-1R8Q_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Zq0ME5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-szczXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-F0oRfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:14:54 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-5O7cNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:14:55 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-syGdq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:14:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:14:55 INFO - Running setup.py install for manifestparser 04:14:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:14:55 INFO - Running setup.py install for mozcrash 04:14:55 INFO - Running setup.py install for mozdebug 04:14:55 INFO - Running setup.py install for mozdevice 04:14:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:14:55 INFO - Running setup.py install for mozfile 04:14:55 INFO - Running setup.py install for mozhttpd 04:14:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:14:55 INFO - Running setup.py install for mozinfo 04:14:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:14:56 INFO - Running setup.py install for mozInstall 04:14:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:14:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:14:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:14:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:14:56 INFO - Running setup.py install for mozleak 04:14:56 INFO - Running setup.py install for mozlog 04:14:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:14:56 INFO - Running setup.py install for moznetwork 04:14:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:14:56 INFO - Running setup.py install for mozprocess 04:14:56 INFO - Running setup.py install for mozprofile 04:14:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:14:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:14:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:14:57 INFO - Running setup.py install for mozrunner 04:14:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:14:57 INFO - Running setup.py install for mozscreenshot 04:14:57 INFO - Running setup.py install for moztest 04:14:57 INFO - Running setup.py install for mozversion 04:14:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:14:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:14:57 INFO - Cleaning up... 04:14:57 INFO - Return code: 0 04:14:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:57 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 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:14:57 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 04:14:57 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 04:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:57 INFO - 'HOME': '/Users/cltbld', 04:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:57 INFO - 'LOGNAME': 'cltbld', 04:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:57 INFO - 'MOZ_NO_REMOTE': '1', 04:14:57 INFO - 'NO_EM_RESTART': '1', 04:14:57 INFO - 'PAGER': '/bin/cat', 04:14:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:57 INFO - 'PWD': '/builds/slave/test', 04:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:57 INFO - 'SHELL': '/bin/bash', 04:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:14:57 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:57 INFO - 'USER': 'cltbld', 04:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:57 INFO - 'XPC_FLAGS': '0x0', 04:14:57 INFO - 'XPC_SERVICE_NAME': '0', 04:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:14:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-wedf3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:14:58 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-uGiq7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-fE4NwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:14:58 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-UGWa30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Y9DRk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:14:58 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-MhReTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:14:58 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-skNgRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:14:58 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-JI2BtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:14:58 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-j8yOBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:14:58 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:14:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Ec1cvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 04:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-KJqjZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:14:59 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)) 04:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-9l7q5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 04:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-t9Qf6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:14:59 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)) 04:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-PLZPwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 04:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-WsnfmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:59 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)) 04:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-qoVB1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 04:14:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:14:59 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Avwd28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:14:59 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)) 04:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:14:59 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)) 04:14:59 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:14:59 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:14:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:02 INFO - Downloading blessings-1.6.tar.gz 04:15:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:15:02 INFO - Installing collected packages: blessings 04:15:02 INFO - Running setup.py install for blessings 04:15:02 INFO - Successfully installed blessings 04:15:02 INFO - Cleaning up... 04:15:02 INFO - Return code: 0 04:15:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:15:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:15:02 INFO - Reading from file tmpfile_stdout 04:15:02 INFO - Current package versions: 04:15:02 INFO - blessings == 1.6 04:15:02 INFO - blobuploader == 1.2.4 04:15:02 INFO - docopt == 0.6.1 04:15:02 INFO - functools32 == 3.2.3-2 04:15:02 INFO - jsonschema == 2.5.1 04:15:02 INFO - manifestparser == 1.1 04:15:02 INFO - mozInstall == 1.12 04:15:02 INFO - mozcrash == 1.0 04:15:02 INFO - mozdebug == 0.1 04:15:02 INFO - mozdevice == 0.49 04:15:02 INFO - mozfile == 1.2 04:15:02 INFO - mozhttpd == 0.7 04:15:02 INFO - mozinfo == 0.9 04:15:02 INFO - mozleak == 0.1 04:15:02 INFO - mozlog == 3.4 04:15:02 INFO - moznetwork == 0.27 04:15:02 INFO - mozprocess == 0.25 04:15:02 INFO - mozprofile == 0.28 04:15:02 INFO - mozrunner == 6.13 04:15:02 INFO - mozscreenshot == 0.1 04:15:02 INFO - mozsystemmonitor == 0.3 04:15:02 INFO - moztest == 0.8 04:15:02 INFO - mozversion == 1.4 04:15:02 INFO - psutil == 3.1.1 04:15:02 INFO - requests == 1.2.3 04:15:02 INFO - wsgiref == 0.1.2 04:15:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:02 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 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:15:02 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 04:15:02 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 04:15:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:15:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:02 INFO - 'HOME': '/Users/cltbld', 04:15:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:02 INFO - 'LOGNAME': 'cltbld', 04:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:02 INFO - 'MOZ_NO_REMOTE': '1', 04:15:02 INFO - 'NO_EM_RESTART': '1', 04:15:02 INFO - 'PAGER': '/bin/cat', 04:15:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:02 INFO - 'PWD': '/builds/slave/test', 04:15:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:02 INFO - 'SHELL': '/bin/bash', 04:15:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:15:02 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:15:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:02 INFO - 'USER': 'cltbld', 04:15:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:02 INFO - 'XPC_FLAGS': '0x0', 04:15:02 INFO - 'XPC_SERVICE_NAME': '0', 04:15:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 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)) 04:15:03 INFO - Cleaning up... 04:15:03 INFO - Return code: 0 04:15:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:03 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 0x103e44b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e47768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8621cb6e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, '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.JmcZWozinP/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.MMyUEE3rDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:15:03 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 04:15:03 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 04:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:03 INFO - 'HOME': '/Users/cltbld', 04:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:03 INFO - 'LOGNAME': 'cltbld', 04:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:03 INFO - 'MOZ_NO_REMOTE': '1', 04:15:03 INFO - 'NO_EM_RESTART': '1', 04:15:03 INFO - 'PAGER': '/bin/cat', 04:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:03 INFO - 'PWD': '/builds/slave/test', 04:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:03 INFO - 'SHELL': '/bin/bash', 04:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:15:03 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:03 INFO - 'USER': 'cltbld', 04:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:03 INFO - 'XPC_FLAGS': '0x0', 04:15:03 INFO - 'XPC_SERVICE_NAME': '0', 04:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:15:03 INFO - Cleaning up... 04:15:03 INFO - Return code: 0 04:15:03 INFO - Running post-action listener: _resource_record_post_action 04:15:03 INFO - Running post-action listener: _start_resource_monitoring 04:15:03 INFO - Starting resource monitoring. 04:15:03 INFO - [mozharness: 2017-03-26 11:15:03.522742Z] Finished create-virtualenv step (success) 04:15:03 INFO - [mozharness: 2017-03-26 11:15:03.523702Z] Running install step. 04:15:03 INFO - Running pre-action listener: _resource_record_pre_action 04:15:03 INFO - Running main action method: install 04:15:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:15:03 INFO - Reading from file tmpfile_stdout 04:15:03 INFO - Detecting whether we're running mozinstall >=1.0... 04:15:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:15:03 INFO - Reading from file tmpfile_stdout 04:15:03 INFO - Output received: 04:15:03 INFO - Usage: mozinstall [options] installer 04:15:03 INFO - Options: 04:15:03 INFO - -h, --help show this help message and exit 04:15:03 INFO - -d DEST, --destination=DEST 04:15:03 INFO - Directory to install application into. [default: 04:15:03 INFO - "/builds/slave/test"] 04:15:03 INFO - --app=APP Application being installed. [default: firefox] 04:15:03 INFO - mkdir: /builds/slave/test/build/application 04:15:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:15:24 INFO - Reading from file tmpfile_stdout 04:15:24 INFO - Output received: 04:15:24 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:15:24 INFO - Running post-action listener: _resource_record_post_action 04:15:24 INFO - [mozharness: 2017-03-26 11:15:24.003991Z] Finished install step (success) 04:15:24 INFO - [mozharness: 2017-03-26 11:15:24.004155Z] Running run-tests step. 04:15:24 INFO - Running pre-action listener: _resource_record_pre_action 04:15:24 INFO - Running main action method: run_tests 04:15:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:15:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:15:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:15:24 INFO - 2017-03-26 04:15:24.018 screenresolution[1672:14273] starting screenresolution argv=screenresolution get 04:15:24 INFO - 2017-03-26 04:15:24.021 screenresolution[1672:14273] Display 0: 1600x1200x32@60 04:15:24 INFO - 2017-03-26 04:15:24.027 screenresolution[1673:14276] starting screenresolution argv=screenresolution list 04:15:24 INFO - Available Modes on Display 0 04:15:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:15:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:15:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:15:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:15:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:15:24 INFO - Intel Iris: 04:15:24 INFO - Chipset Model: Intel Iris 04:15:24 INFO - Type: GPU 04:15:24 INFO - Bus: Built-In 04:15:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:15:24 INFO - Vendor: Intel (0x8086) 04:15:24 INFO - Device ID: 0x0a2e 04:15:24 INFO - Revision ID: 0x0009 04:15:24 INFO - Displays: 04:15:24 INFO - Display: 04:15:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:15:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:15:24 INFO - Display Serial Number: 1600x1200 60 04:15:24 INFO - Main Display: Yes 04:15:24 INFO - Mirror: Off 04:15:24 INFO - Online: Yes 04:15:24 INFO - Rotation: Supported 04:15:24 INFO - Return code: 0 04:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:15:24 INFO - Python 2.7.3 04:15:24 INFO - Return code: 0 04:15:24 INFO - grabbing minidump binary from tooltool 04:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c22d30>, '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 0x103b49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c17be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:15:24 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 04:15:24 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 04:15:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:15:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:15:24 INFO - Return code: 0 04:15:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:15:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:15:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:15:24 INFO - ENV: RUST_BACKTRACE is now 1 04:15:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:15:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:15:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JmcZWozinP/Render', 04:15:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:24 INFO - 'HOME': '/Users/cltbld', 04:15:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:24 INFO - 'LOGNAME': 'cltbld', 04:15:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:15:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:24 INFO - 'MOZ_NO_REMOTE': '1', 04:15:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:15:24 INFO - 'NO_EM_RESTART': '1', 04:15:24 INFO - 'PAGER': '/bin/cat', 04:15:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:24 INFO - 'PWD': '/builds/slave/test', 04:15:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:15:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:24 INFO - 'RUST_BACKTRACE': '1', 04:15:24 INFO - 'SHELL': '/bin/bash', 04:15:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MMyUEE3rDr/Listeners', 04:15:24 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:15:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:24 INFO - 'USER': 'cltbld', 04:15:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:24 INFO - 'XPC_FLAGS': '0x0', 04:15:24 INFO - 'XPC_SERVICE_NAME': '0', 04:15:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:15:24 INFO - mozversion application_buildid: 20170326030204 04:15:24 INFO - mozversion application_changeset: f5e214144799889e2408c4841351f4053f00544e 04:15:24 INFO - mozversion application_display_name: Nightly 04:15:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:15:24 INFO - mozversion application_name: Firefox 04:15:24 INFO - mozversion application_remotingname: firefox 04:15:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:15:24 INFO - mozversion application_vendor: Mozilla 04:15:24 INFO - mozversion application_version: 55.0a1 04:15:24 INFO - mozversion platform_buildid: 20170326030204 04:15:24 INFO - mozversion platform_changeset: f5e214144799889e2408c4841351f4053f00544e 04:15:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:15:24 INFO - mozversion platform_version: 55.0a1 04:15:24 INFO - using testdate: 1490526924 04:15:24 INFO - actual date: 1490526924 04:15:24 INFO - starting webserver on 'localhost:49239' 04:15:24 INFO - SUITE-START | Running 2 tests 04:15:24 INFO - TEST-START | dromaeo_css 04:15:24 INFO - operating with platform_type : mac_ 04:15:24 INFO - Initialising browser for dromaeo_css test... 04:15:24 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpl2AII7/profile http://localhost:49239/getInfo.html) 04:15:27 INFO - PID 1683 | __metrics Screen width/height:1600/1200 04:15:27 INFO - PID 1683 | colorDepth:24 04:15:27 INFO - PID 1683 | Browser inner width/height: 1024/689 04:15:27 INFO - PID 1683 | __metrics 04:15:27 ERROR - PID 1683 | 1490526927260 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:15:27 INFO - PID 1683 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:15:27 INFO - PID 1683 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:15:28 INFO - TEST-INFO | 1683: exit 0 04:15:28 INFO - Browser initialized. 04:15:28 INFO - Running cycle 1/1 for dromaeo_css test... 04:15:28 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpl2AII7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:15:40 INFO - PID 1687 | RSS: Main: 233934848 04:15:40 INFO - PID 1687 | 04:18:45 INFO - PID 1687 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 04:18:46 INFO - PID 1687 | RSS: Main: 349257728 04:18:46 INFO - PID 1687 | 04:20:29 INFO - PID 1687 | ************************* 04:20:29 INFO - PID 1687 | A coding exception was thrown and uncaught in a Task. 04:20:29 INFO - PID 1687 | 04:20:29 ERROR - PID 1687 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:20:29 INFO - PID 1687 | Full stack: 04:20:29 INFO - PID 1687 | ************************* 04:20:29 INFO - PID 1687 | ************************* 04:20:29 INFO - PID 1687 | A coding exception was thrown and uncaught in a Task. 04:20:29 INFO - PID 1687 | 04:20:29 ERROR - PID 1687 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:20:29 INFO - PID 1687 | Full stack: 04:20:29 INFO - PID 1687 | ************************* 04:20:29 INFO - PID 1687 | ************************* 04:20:29 INFO - PID 1687 | A coding exception was thrown and uncaught in a Task. 04:20:29 INFO - PID 1687 | 04:20:29 ERROR - PID 1687 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:20:29 INFO - PID 1687 | Full stack: 04:20:29 INFO - PID 1687 | ************************* 04:20:29 INFO - PID 1687 | ************************* 04:20:29 INFO - PID 1687 | A coding exception was thrown and uncaught in a Task. 04:20:29 INFO - PID 1687 | 04:20:29 ERROR - PID 1687 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:20:29 INFO - PID 1687 | Full stack: 04:20:29 INFO - PID 1687 | ************************* 04:21:49 INFO - PID 1687 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 04:21:50 INFO - PID 1687 | RSS: Main: 366911488 04:21:50 INFO - PID 1687 | 04:24:52 INFO - PID 1687 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 04:24:53 INFO - PID 1687 | RSS: Main: 335306752 04:24:53 INFO - PID 1687 | 04:27:55 INFO - PID 1687 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 04:27:56 INFO - PID 1687 | RSS: Main: 334749696 04:27:56 INFO - PID 1687 | 04:30:59 INFO - PID 1687 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 04:31:00 INFO - PID 1687 | RSS: Main: 337920000 04:31:00 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 04:34:04 INFO - PID 1687 | RSS: Main: 493826048 04:34:04 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | __start_tp_report 04:34:04 INFO - PID 1687 | _x_x_mozilla_page_load 04:34:04 INFO - PID 1687 | _x_x_mozilla_page_load_details 04:34:04 INFO - PID 1687 | |i|pagename|runs| 04:34:04 INFO - PID 1687 | |0;dojo.html;3473.287975964927;3478.6521347865214;3484.8606055757773;3534.8586056557747;3545.273218990107;5996.16053752475;6081.300650425203;6222.471089957354;6231.626102433852;6240.968795156029;4992.9750351248285;6089.604175728575;6092.634441933487;6117.847053823645;6136.171616831726;5385.111456609665;5434.972825135868;5447.12845944649;5492.286002819628;5643.5767317451355;5617.719114044302;5619.971900140494;5636.239107720467;5676.687782171979;5696.08862581987;626.6332206347471;5607.439256074401;5609.467100625455;5623.9437605623825;5654.688992105433;4935.161022626162;5492.642978206443;5532.004239236936;5600.383957764642;5631.746571404296;5539.806106786243;5554.222408862763;5555.611107222496;5559.110542313251;5562.221289019541;6119.479844213247;6159.938400615982;6196.225471816023;6207.565470417073;6236.095766113888;6415.679216039226;6468.838279043014;6483.7730679425995;6491.091247225392;6500.414962653336;6586.506012049068;6653.101831252768;6658.03458498515;6674.766383176614;6693.196816382017;6288.434040936338;6290.779822706229;6292.779752708633;6310.905336419957;6314.968425157845;6557.475401967831;6601.636909969901;6616.139901812733;6746.156730408699;6794.5583537070925;6343.207099112611;6449.903251451232;6454.031895215755;6457.3865482779065;6460.483161347081;6426.903596446057;6641.369069938348;6655.767048153291;6664.400203981665;6690.063391125246;6330.05049242608;6367;6368.10846481493;6387.297397286295;6388.073729309317;6405.711742971577;6412.871742565216;6550.6069635822005;6591.736330546724;6599.340065993362;5313.07021271276;5318.601104917147;5325.281087053276;5340.092184328673;5354.839354819362;4643.4660014098135;4651.604613607814;4662.650301227353;4685.882852928704;4708.246680531099;6191.164192834;6235.563510554309;6248.625082495065;6253.781117660951;6256.874862502816;6644.568103073285;6651.235107962646;6651.634160121143;6655.700493477805;6662.56693314933;5325.360956685223;6105.511559075263;6125.632462052291;6185.783497577563;6220.34686357935;5732.512736417368;5752.309722833287;5813.069908814654;5852.180694702745;5857.414258574108;6782.830429239111;6806.353396427331;6807.353301436356;6809.89785153233;6812.18253809556;6562.310957349409;6565.244996825426;6622.768203112868;6630.071789949508;6635.933640663531;6566.0479230512265;6648.069270302065;6649.800505984828;6717.764878229238;6739.989001649792;6517.869642607026;6560.803175904731;6581.111549940697;6583.506237032338;6586.769463068769;6095.939040609537;6268.247810262797;6610.041543976193;6633.535652504278;6655.367740064686;5391.514763671288;6252.155958945484;6308.558400911892;6483.962565989419;6524.510661700297;6304.306526282014;6405.775797847053;6446.8388290293115;6457.160569125984;6461.547691661539;6213.596116252429;6269.31037585856;6353.364663533795;6376.585521941059;6380.968095159495;6259.65571893532;6303.086052522451;6402.647854367868;6409.583377080475;6438.484921206386;6214.533909956862;6334.556581039283;6353.301136875032;6361.363863613602;6362.840928976812 04:34:04 INFO - PID 1687 | |1;ext.html;665.3014334948306;686.0806519264187;707.3527722134247;1113.1261959361898;1128.7686024365005;27615.2384761524;28249.293767655792;28285.858570707143;37243.81378093106;37745.24509509815;41667.37498937518;42080.57919420805;42218.36672449916;42259.78870105645;42343;8699.60851761669;8864.51245181518;8866.51234182123;8878.378513504058;8894.59974301158;6147.077938309245;6290.119383286344;8476.66093356265;8667.913320866774;8684.696035638754;8696.260817830493;8735.606897689619;8757.47455152693;8761.868571971394;8763.737087887373;8631.223189912907;8725.345599080094;8726.432781869222;8735.1701588349;8743.431676940978;8530.82938341236;8624.956875215647;8650.65397384104;8671;8733.170348816915;39257.41113883294;39384;39432.40851387232;39582.406263906065;39607;3037.179961410429;3064.2952121012067;3065.111117775865;3079.0762771168784;3079.491883839164;28122.969257685578;28333.291667708265;28649.14052578426;28759.137225883256;29563.852180739173;2783.44831696741;2791.274268690155;2791.9581206281923;2812.2125804774696;2897.058456001808;327169;327702;330617;331238;331445;51409.74295128501;51532.484675153515;51684.48315516872;51749.741251293504;51789.223161652604;54576;54579.72710136424;54623;54638;54646.726766365915;14500;14742.926285368505;14755.336009879582;14759.262036898112;14789.556313310402;10038.397696138256;11888.108391870644;12015.459304331325;12017.939910300393;12034.338111403957;11053.392063436428;11239.381833999045;11274.210805243718;12560;12761.61715148547;4325.524192338903;4412.646988240933;4958.636449819894;5163.302954101224;5198.2722418861385;874.4010352908278;935.6117211357347;986.4820968991337;1008.4201584089031;1013.8428543575758;23104.306870793902;23496.530069398515;24888.626670599955;24911.87544062268;24977.750222497543;1083.1767856429012;1088.4068182840372;1092.2900114925362;1128.8306753986967;1133.6372360844616;12563.685907852378;12630.684232894067;12647.557335493213;12660.683482913;12720.363981800872;409017;410078;410078;410140.94929525163;410340;57606.42393576095;57630.711846441336;57792;57805.265842024804;58111.8377632445;1442.935067921946;1462.6782107936663;1512.1154124836874;1516.696660667871;1519.8176218853807;23918.52162956766;28391.574126387706;28560;29089.854550727112;29185.708142918298;54037;54387.72806136102;54482.72758636181;54505;54509.454905452025;377942;378972.1051394725;380339;380620;380781;26362.868185659718;33814.830925845214;33890.49164262587;34561.827190863885;34616.82691586526;33327.666723332455;34064.48903266403;39711.40432893567;43607.34588981231;43889;28284.71715282821;28482.430351392442;29150.12549623515;29969.850150749106;30374;26063.011014932585;29325.411043433836;29573.112806615834;29899.55150672698;30164.84917575398;29505.85247073837;36169;36381.090472737334;36596.45105323369;36803.07992300214;22069.33791986243;31127.84436077805;31653.733850646637;31764.729410823307;32100.35799284048;21978.340649780534;22597.531160474515;22748.31755047351;23874.16440424646;24240.393990150387 04:34:04 INFO - PID 1687 | |2;jquery.html;407.41332481227033;427.1222637479979;432.06889153873414;436.87112301870957;445.4009357414279;20717.171313147457;20833.479163020933;21478.355649330526;21780.346589602297;22027.118915243413;21239.93800309982;22509.887450562765;23407.82960851959;23506.059757609717;24147.879260603717;6089.842929843327;6303.401176888188;6310;6320.525960552954;6322.74709011639;6021.217241758565;6263.749450022016;6307.400796924285;6313.747450101991;6321;6066.272047354324;6257.843553911143;6263.123162757218;6267.71795269214;6282.560220784547;6259.749610015594;6276.089966954812;6297.338779428185;6302.023186406114;6306.635011870549;5416.783328666848;5512.586556008276;5577.553795696334;5604.915926261109;5610.214569960209;26803.597946030826;28086.297842553897;28217.435651286858;28436.715632843614;28730.138095857157;2017.6482252627434;2029.6853987631794;2039.327022082724;2070.1615845582414;2120.1095539873136;15071.849281507155;15657.765133523006;16122.83877161225;16340.673186536322;16814.243359048873;1695.008420074258;1713.31238729851;1713.7943446786464;1725.05984238589;1726.3526177683368;128507.35746321209;142544.28727856293;153807.2309638456;165953;167020;9674.854877176847;12094.460163365791;12113.212641178296;12149.878501215051;12312.199707019194;13366.198028118344;15903.966242194452;16231.51305460838;16232.431864884673;16235.675286494205;7179.425645948311;8713.738587842248;8916.305901878623;8969.461832290084;9072.27421806254;8575.27110195628;8581.914180858112;8612.827743445221;8759.861218041613;8775.956120219485;7578.772636820904;7718.498297610749;7731.806704832312;7751.224877512316;7768.125793456457;2097.538541520863;2109.7362829646295;2112.7570329412006;2116.407405926343;2287.885605719707;778.0352363069753;919.3610440743753;919.5586118662945;960.9087136722001;964.2575217082807;20574.58850822975;21110.461250831144;21144.89427552883;21146;21212.469688257916;951.8001219743793;951.9571619277149;991.2169386184834;997.2171845101706;999.7500624843789;10348.291527355035;10773.299735517166;10842.674719758419;10876.72808179786;10877.129829613612;664357;679022;690873;696249;703657.9634203694;79048;84255;141506.29246853906;143689;156530;953.4041224234853;1147.5008371358485;1165.4813607944384;1171.6367925942984;1171.9589814356627;20496.487587810425;21238;21566;21758.020889060346;22373.552528949655;51051.74474127606;53396;60273.69863150656;61127.91389420874;61750.69124654348;71002;80846.19153808622;82741.58629206815;83309;84219.5789021051;8328.500289982621;8570.357223208159;8717.607707653298;8794.120587941155;8823.602937867683;11198.16013798952;13925.791113133513;13930.442782288595;14315.355808988828;14456.13263204211;11107.777844443228;11509.597164099216;11813.468393922121;11878.940605296919;12040.21738587024;9649.951750241204;10713.76791668968;11195.88804111981;11204.159688023594;11510.194286399872;10298.433586152834;10452.895471045496;10562.366258024544;10583.206259530412;10832.620858269922;6155.753769849156;7100.467464940047;8584.828303433771;8607.311415086653;8941.418807777474;9954.352967057119;10549.470326802417;11560.884391156318;11577.7105572358;11956.70108247266 04:34:04 INFO - PID 1687 | |3;mootools.html;1345.8519562848085;1415.6036309833244;1431.8353389360227;1432.5845504803608;1436.6408397900527;10423.947880260597;10874.401907895062;10893.891061089387;10900.890991090097;10903.83644245335;10626;10668.093212076983;10895.782084358309;11002.669919902395;11006.834897476525;2124.957500849982;2455.680761501007;2493.4015836199274;2494.8004159667184;2501.2746303571944;2203.6584329428883;2306.850054746436;2313.0516488239823;2315.235972129193;2468.9135880244194;2064.060852312202;2143.742750869898;2145.281330754199;2148.892555372233;2179.7384313882358;2103.663413853784;2131.7228760261223;2385.582523058458;2472.863992480413;2476.319012271622;2449.546833835728;2456.8403053801626;2457.1522824625476;2458.0782206672498;2460.5694003549306;10880.12958963281;10971.725706857393;10974.945125274322;10977.06694930932;10990.120790336754;5468.7265636718;5662.8301150965535;5683.772649094031;6124.5100391968535;6196.10156527301;3902.512185976753;3968.7023473239333;4042;4063.451434056394;4071.653909417703;1186.9643910682694;1188.3345326617123;1193.6419074277683;1199.1006744941294;1224.253205544617;15142.54572362831;15174.620634484116;15229.086254824746;15265.313060912396;15279.923600382039;5147.6911385317;5353.839384818461;5429.131338985743;5435.266239057755;5463.781448742086;11268.211225214156;11499.310041397543;11505.942470287762;11522.251053681484;11530.250533715282;6261;6318.4313411793155;6320.273168585672;6324;6328;5721.5136713379825;5920.526357891358;5930.9406905931255;5975.19334889793;6012.428819262163;4103.076807718299;4209.5579964103945;4234.661227101825;4282.614564689131;4284.421603083606;1264.64589914824;1265.471828893957;1278.4758249117817;1278.4949944771763;1282.7178020835402;722.1298335505703;755.5995322479095;757.1519897714485;779.2168870285441;785.5090568394753;5903.26209223847;5975.282966044103;6018.458338749533;6064.75740970365;6077.088436734525;562.361719448429;575.32686756495;588.6791698524314;597.2892258212713;598.7395482964924;3820.770753754728;4089.9795501022304;4216.557261487561;4240.109576988805;4252.298370768788;13439.462421503225;14110.85889141116;14131.71736565263;14149.43402263898;14169.149851008973;13246.801297980537;13809.585712428643;14089.225092620005;14089.929550352388;14104.730574248368;9937.65218217359;10206.591736330613;10211.438370889524;10234.59061637541;10280.537375818107;5531.612787105025;6597.571157874723;6621.172353455818;6868.278830722702;6874.415674667709;12095;12457.377131143225;12476.126671132934;12498.37508124572;12739.681507962376;12728.809067863804;12860.935695321463;12998.480060797463;13014.739705205653;13028.674283142997;9020;9166.923512860729;9339.813203736023;9349.532523373668;9355.953220233856;10207.846882296622;10259.127974121984;10349.74125646865;10369.429681367597;10370.792584148125;8525.701600443954;8687.435316704394;8790.736277911672;8841.336899732418;8867;8461.703840365557;8477.406581539233;8478.491290522588;8566.18621230982;8584.91415085841;7343.889841652486;7731.497452665559;7839.137694853447;7847.450678452454;7858.096318923397;4820.71075735457;5093.567046801063;5673.8865222694485;5728;5954.732037058429;5518.503334699896;5987.161797348462;6010.849728756816;6067.544934130046;6192.133101365723 04:34:04 INFO - PID 1687 | |4;prototype.html;393.3234836081939;399.7002248313765;400.7340177552944;410.2062509044998;410.2246753635627;5099.923501147486;5509.889802203958;5812.564057695668;6092.969535152323;6138.987067133923;5872.588918775677;5934.6647004423985;6069.757209711606;6076.9392306076925;6141.539384546166;4733.715977041388;4736.9920100735935;4746.477887432379;4754.595859351959;4754.881127971811;4774.092922344761;4777.90444191116;4786.497417771118;4793.010900476298;4796.952030479685;3489.4242449995722;3856.2865869814173;4195.489848412953;4517.560517320643;4676.205045142334;3844.365679662866;4678.812847486113;4706.788194531255;4709.740964246965;4721.244600863845;3608.3324584951606;4181.937270940938;4188.204241194163;4190.434291370657;4199.076203235283;5095.79616815327;5119.436861945183;5429.592780541475;5844.590878638497;5916;5473.370562385337;5495.780168793243;5499.312585926759;5500.4499550045075;5502.477264659851;5207;6064.9689552776135;6516.902246466307;6581.012848072827;6584.670766461706;3805.8858234253016;3944.2900277949957;4022.6982976276895;4576.313552967048;4663.60359369454;5598.776048958037;5922.763089476459;6553.3446655334565;6766;6871.622060786704;3911.706622003361;4118.485189351331;4392.363107349416;4483.327500874895;4567.200739870509;4187.748735075906;4373.053990973986;4801.663883528119;4802.51974802517;4851.757412129379;4739.691920025188;5469.890602188014;5490.615656903979;5493.752781124859;5512.6692398456225;5138.38339399267;5221;6364.109024736541;6471.773487927994;6560.704768285439;1640.4668482743157;1663.717168081429;1663.9168041598114;1670.991645041767;1674.6818104560095;1111.3554138599593;1143.114086582905;1151.8099513580166;1152.2395219155317;1169.6374124021581;5424.83725488236;6085.2393450818645;6143.96928015357;6155.876882462415;6162.7843025494785;5901.967155747727;6522.510811689142;6769.239997600659;6800.607429016807;6809.965950170217;4603.608693261043;4846.3699719037;5364.585936328668;5836.795712150054;6117.296510901214;2919.2118128105717;3455.8617655293506;3483.7561370704316;3511.736619753529;3521.841517131735;6060.484858787053;6510.446612037935;6833.813179782522;6919.37725604698;6952.652367381611;5186.858891043964;5748.511376533042;6589.80230593083;6934.133233345832;6966.616836073963;5294.279359208195;6577.835554111186;6826.692798823966;6889.621070841152;6931.272216417305;6627.801165965029;6699.464042876461;6704.966475167593;6706.8993965089585;6717.764878229238;5797.507211887037;6456.644884531396;6660.101870966751;6664.166979127609;6701.564398314094;5687.516561092353;6782.898256526255;6872.209695884837;6901.378875901193;6916.827079322856;4161.500619925629;4743.45450273223;4764.30917516965;4766.904661906673;4781.665283430127;5680.318361796611;6405.686834198885;6632.535722499379;6669.966650166718;6781.42357899584;5937.643741375531;6445.452136568444;6496.610203387811;6501.9674901625185;6511.79531786621;5871.735771890189;6213.7009306556865;6382.32985536512;6396.71214795326;6535.901961470487;6363;6388.968055159695;6390.265119511129;6391.392817682313;6415.615063096229;5781.508571771446;6343.794679010974;6506.4469520091325;6512.120863683342;6530.68974476843;5583.55331573465;5605;5978.940210597838;6288.968555157195;6450.419462248233 04:34:04 INFO - PID 1687 | |5;yui.html;709.9999999999999;728.3735987051139;740.93702035327;741.8850078237872;769.5651956644492;15365.154916479603;15702.136382498973;16010.519684409486;16010.839891601081;16049.11729854859;14974.775378369333;15089.698206035873;15092.698146037073;15115.01752386094;15124.773128403083;820.885076089345;913.1735779119907;923.9769005774842;933.9066093390641;967.762898089969;962.4850704872895;969.2633598465146;975.2100798353321;978.8238117138912;980.7352014955958;1005.5776573839005;1008.1027885182194;1009.9949500252525;1010.0303708439907;1014.4217795856365;1001.5092604623718;1001.884783249921;1002.4336250018716;1002.4686915934566;1005.8440941654019;743.8475112601903;745.5824738146655;745.7501736918139;746.3655892491394;748.2704363245846;15307.234638268154;15397.307121179574;15543.922280388639;16798.58003549909;16857.325706971707;13394.933025334909;13855.891528677681;14558.199299038548;14630;14982.475613353481;11712.238704484267;11724.53784705213;11913.22564033335;11986.580469683606;12214.694632634166;2746.835189888613;3060.0360886320736;3066.877324907023;3088.4440800655875;3137.074563003919;95927.52036239774;114294.57023301831;114525.58781470974;117981.41009294984;118260;10783.83824242637;11410.600628977945;11595.130365222642;11930.582429614922;12145.939270303592;25005.249842504752;25370;25399.492010159694;25560.616590751153;25577.744222557907;7876.84246315082;7943.086545047278;7995.400344974034;7995.840083198304;8013.11855695873;4154.896127596834;4194.902221472584;4220.198162349144;4235.7670328131635;4236.4492615959725;4111.09555897702;4148.170365926827;4187;4232.111256636079;4254.893627659333;797.2426195114664;802.4864086984335;805.5690205740018;809.7608473178525;827.5034979012544;501.9222020586815;549.2941570082425;562.8846086552247;586.1939832729996;602.2652364115771;9605.115268376723;9909.856008934661;9941.900580994243;10035.79928401428;10106;555.4362322242927;588.2499812738793;592.5467017731439;610.2646311194999;618.5082859127004;3905.1478761957324;4488.483824360175;4700.341952126705;4815.975920120377;5125.121742760027;91757.54121229485;98809.01190987998;99838.5008074955;102676;105362.47318763357;62001;88075;88452;88560;90406;48196;51840.74079629578;51861.222081668806;52133.478665213624;58345.708271458374;9381.437113773196;9962.352447090916;10472.476376181314;10596.364218146937;10738.355698658106;26205.213843584723;28048.579271311355;34630.13424664303;35793.28413431769;36400;49827.50172498373;50257.49742502675;50280.74859625825;50341.993160137325;50800;13125.934370328469;13400.329983500591;13414.530491432753;13864.930675346559;14266.001379903308;14279.64300892486;14287.285635717964;14347.354369053626;14403.135811851322;14453.927730361282;13450.394732237268;13481;13579.321033948461;13595.45618175262;13730.352357717138;12973.286469244282;13043.869561304646;13427.530036448677;13829.930850345683;14116.153030818185;13239.801402979156;13284.468621255428;13323.66690832737;13559.525416610371;13734;8772.91227087721;9738.853917191389;9752.414855108716;9772.951135244279;10023.296039673127;8201;9077.409968352036;10113;10182.23633227496;11027.964552257406 04:34:04 INFO - PID 1687 | __end_tp_report 04:34:04 INFO - PID 1687 | __start_cc_report 04:34:04 INFO - PID 1687 | _x_x_mozilla_cycle_collect,1333 04:34:04 INFO - PID 1687 | __end_cc_report 04:34:04 INFO - PID 1687 | __startTimestamp1490528044464__endTimestamp 04:34:04 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | ------- Summary: start ------- 04:34:04 INFO - PID 1687 | Number of tests: 6 04:34:04 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | [#0] dojo.html Cycles:165 Average:6056.98 Median:6304.31 stddev:801.58 (12.7%) stddev-sans-first:777.99 04:34:04 INFO - PID 1687 | Values: 3473.3 3478.7 3484.9 3534.9 3545.3 5996.2 6081.3 6222.5 6231.6 6241.0 4993.0 6089.6 6092.6 6117.8 6136.2 5385.1 5435.0 5447.1 5492.3 5643.6 5617.7 5620.0 5636.2 5676.7 5696.1 626.6 5607.4 5609.5 5623.9 5654.7 4935.2 5492.6 5532.0 5600.4 5631.7 5539.8 5554.2 5555.6 5559.1 5562.2 6119.5 6159.9 6196.2 6207.6 6236.1 6415.7 6468.8 6483.8 6491.1 6500.4 6586.5 6653.1 6658.0 6674.8 6693.2 6288.4 6290.8 6292.8 6310.9 6315.0 6557.5 6601.6 6616.1 6746.2 6794.6 6343.2 6449.9 6454.0 6457.4 6460.5 6426.9 6641.4 6655.8 6664.4 6690.1 6330.1 6367.0 6368.1 6387.3 6388.1 6405.7 6412.9 6550.6 6591.7 6599.3 5313.1 5318.6 5325.3 5340.1 5354.8 4643.5 4651.6 4662.7 4685.9 4708.2 6191.2 6235.6 6248.6 6253.8 6256.9 6644.6 6651.2 6651.6 6655.7 6662.6 5325.4 6105.5 6125.6 6185.8 6220.3 5732.5 5752.3 5813.1 5852.2 5857.4 6782.8 6806.4 6807.4 6809.9 6812.2 6562.3 6565.2 6622.8 6630.1 6635.9 6566.0 6648.1 6649.8 6717.8 6740.0 6517.9 6560.8 6581.1 6583.5 6586.8 6095.9 6268.2 6610.0 6633.5 6655.4 5391.5 6252.2 6308.6 6484.0 6524.5 6304.3 6405.8 6446.8 6457.2 6461.5 6213.6 6269.3 6353.4 6376.6 6381.0 6259.7 6303.1 6402.6 6409.6 6438.5 6214.5 6334.6 6353.3 6361.4 6362.8 04:34:04 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | [#1] ext.html Cycles:180 Average:51613.42 Median:33852.66 stddev:99094.69 (292.7%) stddev-sans-first:99298.84 04:34:04 INFO - PID 1687 | Values: 665.3 686.1 707.4 1113.1 1128.8 27615.2 28249.3 28285.9 37243.8 37745.2 41667.4 42080.6 42218.4 42259.8 42343.0 8699.6 8864.5 8866.5 8878.4 8894.6 6147.1 6290.1 8476.7 8667.9 8684.7 8696.3 8735.6 8757.5 8761.9 8763.7 8631.2 8725.3 8726.4 8735.2 8743.4 8530.8 8625.0 8650.7 8671.0 8733.2 39257.4 39384.0 39432.4 39582.4 39607.0 3037.2 3064.3 3065.1 3079.1 3079.5 28123.0 28333.3 28649.1 28759.1 29563.9 2783.4 2791.3 2792.0 2812.2 2897.1 327169.0 327702.0 330617.0 331238.0 331445.0 51409.7 51532.5 51684.5 51749.7 51789.2 54576.0 54579.7 54623.0 54638.0 54646.7 14500.0 14742.9 14755.3 14759.3 14789.6 10038.4 11888.1 12015.5 12017.9 12034.3 11053.4 11239.4 11274.2 12560.0 12761.6 4325.5 4412.6 4958.6 5163.3 5198.3 874.4 935.6 986.5 1008.4 1013.8 23104.3 23496.5 24888.6 24911.9 24977.8 1083.2 1088.4 1092.3 1128.8 1133.6 12563.7 12630.7 12647.6 12660.7 12720.4 409017.0 410078.0 410078.0 410140.9 410340.0 57606.4 57630.7 57792.0 57805.3 58111.8 1442.9 1462.7 1512.1 1516.7 1519.8 23918.5 28391.6 28560.0 29089.9 29185.7 54037.0 54387.7 54482.7 54505.0 54509.5 377942.0 378972.1 380339.0 380620.0 380781.0 26362.9 33814.8 33890.5 34561.8 34616.8 33327.7 34064.5 39711.4 43607.3 43889.0 28284.7 28482.4 29150.1 29969.9 30374.0 26063.0 29325.4 29573.1 29899.6 30164.8 29505.9 36169.0 36381.1 36596.5 36803.1 22069.3 31127.8 31653.7 31764.7 32100.4 21978.3 22597.5 22748.3 23874.2 24240.4 04:34:04 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | [#2] jquery.html Cycles:180 Average:39039.28 Median:28151.87 stddev:114631.54 (407.2%) stddev-sans-first:114916.41 04:34:04 INFO - PID 1687 | Values: 407.4 427.1 432.1 436.9 445.4 20717.2 20833.5 21478.4 21780.3 22027.1 21239.9 22509.9 23407.8 23506.1 24147.9 6089.8 6303.4 6310.0 6320.5 6322.7 6021.2 6263.7 6307.4 6313.7 6321.0 6066.3 6257.8 6263.1 6267.7 6282.6 6259.7 6276.1 6297.3 6302.0 6306.6 5416.8 5512.6 5577.6 5604.9 5610.2 26803.6 28086.3 28217.4 28436.7 28730.1 2017.6 2029.7 2039.3 2070.2 2120.1 15071.8 15657.8 16122.8 16340.7 16814.2 1695.0 1713.3 1713.8 1725.1 1726.4 128507.4 142544.3 153807.2 165953.0 167020.0 9674.9 12094.5 12113.2 12149.9 12312.2 13366.2 15904.0 16231.5 16232.4 16235.7 7179.4 8713.7 8916.3 8969.5 9072.3 8575.3 8581.9 8612.8 8759.9 8776.0 7578.8 7718.5 7731.8 7751.2 7768.1 2097.5 2109.7 2112.8 2116.4 2287.9 778.0 919.4 919.6 960.9 964.3 20574.6 21110.5 21144.9 21146.0 21212.5 951.8 952.0 991.2 997.2 999.8 10348.3 10773.3 10842.7 10876.7 10877.1 664357.0 679022.0 690873.0 696249.0 703658.0 79048.0 84255.0 141506.3 143689.0 156530.0 953.4 1147.5 1165.5 1171.6 1172.0 20496.5 21238.0 21566.0 21758.0 22373.6 51051.7 53396.0 60273.7 61127.9 61750.7 71002.0 80846.2 82741.6 83309.0 84219.6 8328.5 8570.4 8717.6 8794.1 8823.6 11198.2 13925.8 13930.4 14315.4 14456.1 11107.8 11509.6 11813.5 11878.9 12040.2 9650.0 10713.8 11195.9 11204.2 11510.2 10298.4 10452.9 10562.4 10583.2 10832.6 6155.8 7100.5 8584.8 8607.3 8941.4 9954.4 10549.5 11560.9 11577.7 11956.7 04:34:04 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | [#3] mootools.html Cycles:180 Average:6772.25 Median:2484.86 stddev:4250.59 (171.1%) stddev-sans-first:4242.96 04:34:04 INFO - PID 1687 | Values: 1345.9 1415.6 1431.8 1432.6 1436.6 10423.9 10874.4 10893.9 10900.9 10903.8 10626.0 10668.1 10895.8 11002.7 11006.8 2125.0 2455.7 2493.4 2494.8 2501.3 2203.7 2306.9 2313.1 2315.2 2468.9 2064.1 2143.7 2145.3 2148.9 2179.7 2103.7 2131.7 2385.6 2472.9 2476.3 2449.5 2456.8 2457.2 2458.1 2460.6 10880.1 10971.7 10974.9 10977.1 10990.1 5468.7 5662.8 5683.8 6124.5 6196.1 3902.5 3968.7 4042.0 4063.5 4071.7 1187.0 1188.3 1193.6 1199.1 1224.3 15142.5 15174.6 15229.1 15265.3 15279.9 5147.7 5353.8 5429.1 5435.3 5463.8 11268.2 11499.3 11505.9 11522.3 11530.3 6261.0 6318.4 6320.3 6324.0 6328.0 5721.5 5920.5 5930.9 5975.2 6012.4 4103.1 4209.6 4234.7 4282.6 4284.4 1264.6 1265.5 1278.5 1278.5 1282.7 722.1 755.6 757.2 779.2 785.5 5903.3 5975.3 6018.5 6064.8 6077.1 562.4 575.3 588.7 597.3 598.7 3820.8 4090.0 4216.6 4240.1 4252.3 13439.5 14110.9 14131.7 14149.4 14169.1 13246.8 13809.6 14089.2 14089.9 14104.7 9937.7 10206.6 10211.4 10234.6 10280.5 5531.6 6597.6 6621.2 6868.3 6874.4 12095.0 12457.4 12476.1 12498.4 12739.7 12728.8 12860.9 12998.5 13014.7 13028.7 9020.0 9166.9 9339.8 9349.5 9356.0 10207.8 10259.1 10349.7 10369.4 10370.8 8525.7 8687.4 8790.7 8841.3 8867.0 8461.7 8477.4 8478.5 8566.2 8584.9 7343.9 7731.5 7839.1 7847.5 7858.1 4820.7 5093.6 5673.9 5728.0 5954.7 5518.5 5987.2 6010.8 6067.5 6192.1 04:34:04 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | [#4] prototype.html Cycles:180 Average:5225.03 Median:5601.89 stddev:1585.78 (28.3%) stddev-sans-first:1548.21 04:34:04 INFO - PID 1687 | Values: 393.3 399.7 400.7 410.2 410.2 5099.9 5509.9 5812.6 6093.0 6139.0 5872.6 5934.7 6069.8 6076.9 6141.5 4733.7 4737.0 4746.5 4754.6 4754.9 4774.1 4777.9 4786.5 4793.0 4797.0 3489.4 3856.3 4195.5 4517.6 4676.2 3844.4 4678.8 4706.8 4709.7 4721.2 3608.3 4181.9 4188.2 4190.4 4199.1 5095.8 5119.4 5429.6 5844.6 5916.0 5473.4 5495.8 5499.3 5500.4 5502.5 5207.0 6065.0 6516.9 6581.0 6584.7 3805.9 3944.3 4022.7 4576.3 4663.6 5598.8 5922.8 6553.3 6766.0 6871.6 3911.7 4118.5 4392.4 4483.3 4567.2 4187.7 4373.1 4801.7 4802.5 4851.8 4739.7 5469.9 5490.6 5493.8 5512.7 5138.4 5221.0 6364.1 6471.8 6560.7 1640.5 1663.7 1663.9 1671.0 1674.7 1111.4 1143.1 1151.8 1152.2 1169.6 5424.8 6085.2 6144.0 6155.9 6162.8 5902.0 6522.5 6769.2 6800.6 6810.0 4603.6 4846.4 5364.6 5836.8 6117.3 2919.2 3455.9 3483.8 3511.7 3521.8 6060.5 6510.4 6833.8 6919.4 6952.7 5186.9 5748.5 6589.8 6934.1 6966.6 5294.3 6577.8 6826.7 6889.6 6931.3 6627.8 6699.5 6705.0 6706.9 6717.8 5797.5 6456.6 6660.1 6664.2 6701.6 5687.5 6782.9 6872.2 6901.4 6916.8 4161.5 4743.5 4764.3 4766.9 4781.7 5680.3 6405.7 6632.5 6670.0 6781.4 5937.6 6445.5 6496.6 6502.0 6511.8 5871.7 6213.7 6382.3 6396.7 6535.9 6363.0 6389.0 6390.3 6391.4 6415.6 5781.5 6343.8 6506.4 6512.1 6530.7 5583.6 5605.0 5978.9 6289.0 6450.4 04:34:04 INFO - PID 1687 | 04:34:04 INFO - PID 1687 | [#5] yui.html Cycles:180 Average:18879.76 Median:3112.76 stddev:27230.68 (874.8%) stddev-sans-first:27272.89 04:34:04 INFO - PID 1687 | Values: 710.0 728.4 740.9 741.9 769.6 15365.2 15702.1 16010.5 16010.8 16049.1 14974.8 15089.7 15092.7 15115.0 15124.8 820.9 913.2 924.0 933.9 967.8 962.5 969.3 975.2 978.8 980.7 1005.6 1008.1 1010.0 1010.0 1014.4 1001.5 1001.9 1002.4 1002.5 1005.8 743.8 745.6 745.8 746.4 748.3 15307.2 15397.3 15543.9 16798.6 16857.3 13394.9 13855.9 14558.2 14630.0 14982.5 11712.2 11724.5 11913.2 11986.6 12214.7 2746.8 3060.0 3066.9 3088.4 3137.1 95927.5 114294.6 114525.6 117981.4 118260.0 10783.8 11410.6 11595.1 11930.6 12145.9 25005.2 25370.0 25399.5 25560.6 25577.7 7876.8 7943.1 7995.4 7995.8 8013.1 4154.9 4194.9 4220.2 4235.8 4236.4 4111.1 4148.2 4187.0 4232.1 4254.9 797.2 802.5 805.6 809.8 827.5 501.9 549.3 562.9 586.2 602.3 9605.1 9909.9 9941.9 10035.8 10106.0 555.4 588.2 592.5 610.3 618.5 3905.1 4488.5 4700.3 4816.0 5125.1 91757.5 98809.0 99838.5 102676.0 105362.5 62001.0 88075.0 88452.0 88560.0 90406.0 48196.0 51840.7 51861.2 52133.5 58345.7 9381.4 9962.4 10472.5 10596.4 10738.4 26205.2 28048.6 34630.1 35793.3 36400.0 49827.5 50257.5 50280.7 50342.0 50800.0 13125.9 13400.3 13414.5 13864.9 14266.0 14279.6 14287.3 14347.4 14403.1 14453.9 13450.4 13481.0 13579.3 13595.5 13730.4 12973.3 13043.9 13427.5 13829.9 14116.2 13239.8 13284.5 13323.7 13559.5 13734.0 8772.9 9738.9 9752.4 9773.0 10023.3 8201.0 9077.4 10113.0 10182.2 11028.0 04:34:04 INFO - PID 1687 | -------- Summary: end -------- 04:34:04 INFO - PID 1687 | 04:34:05 INFO - TEST-INFO | 1687: exit 0 04:34:05 INFO - TEST-OK | dromaeo_css | took 1120507ms 04:34:05 INFO - TEST-START | kraken 04:34:05 INFO - operating with platform_type : mac_ 04:34:05 INFO - Initialising browser for kraken test... 04:34:05 INFO - TEST-INFO | started process 1758 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp_0wtMU/profile http://localhost:49239/getInfo.html) 04:34:07 INFO - PID 1758 | __metrics Screen width/height:1600/1200 04:34:07 INFO - PID 1758 | colorDepth:24 04:34:07 INFO - PID 1758 | Browser inner width/height: 1024/689 04:34:07 INFO - PID 1758 | __metrics 04:34:07 ERROR - PID 1758 | 1490528047688 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:34:07 INFO - PID 1758 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:34:07 INFO - PID 1758 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:34:08 INFO - TEST-INFO | 1758: exit 0 04:34:08 INFO - Browser initialized. 04:34:08 INFO - Running cycle 1/1 for kraken test... 04:34:08 INFO - TEST-INFO | started process 1760 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp_0wtMU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:34:20 INFO - PID 1760 | RSS: Main: 234393600 04:34:20 INFO - PID 1760 | 04:34:23 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 04:34:24 INFO - PID 1760 | RSS: Main: 284340224 04:34:24 INFO - PID 1760 | 04:34:27 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 04:34:27 INFO - PID 1760 | RSS: Main: 286658560 04:34:27 INFO - PID 1760 | 04:34:31 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 04:34:31 INFO - PID 1760 | RSS: Main: 376586240 04:34:31 INFO - PID 1760 | 04:34:34 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 04:34:34 INFO - PID 1760 | RSS: Main: 282185728 04:34:34 INFO - PID 1760 | 04:34:36 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:34:37 INFO - PID 1760 | RSS: Main: 284151808 04:34:37 INFO - PID 1760 | 04:34:40 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 04:34:40 INFO - PID 1760 | RSS: Main: 326057984 04:34:40 INFO - PID 1760 | 04:34:43 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 04:34:44 INFO - PID 1760 | RSS: Main: 331657216 04:34:44 INFO - PID 1760 | 04:34:47 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 04:34:48 INFO - PID 1760 | RSS: Main: 330043392 04:34:48 INFO - PID 1760 | 04:34:49 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:34:50 INFO - PID 1760 | RSS: Main: 303943680 04:34:50 INFO - PID 1760 | 04:34:52 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:34:53 INFO - PID 1760 | RSS: Main: 307937280 04:34:53 INFO - PID 1760 | 04:34:55 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:34:56 INFO - PID 1760 | RSS: Main: 309194752 04:34:56 INFO - PID 1760 | 04:34:59 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:34:59 INFO - PID 1760 | RSS: Main: 324931584 04:34:59 INFO - PID 1760 | 04:35:02 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:35:03 INFO - PID 1760 | RSS: Main: 319225856 04:35:03 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 04:35:05 INFO - PID 1760 | RSS: Main: 380444672 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | __start_tp_report 04:35:05 INFO - PID 1760 | _x_x_mozilla_page_load 04:35:05 INFO - PID 1760 | _x_x_mozilla_page_load_details 04:35:05 INFO - PID 1760 | |i|pagename|runs| 04:35:05 INFO - PID 1760 | |0;ai-astar;113;110;111;112;112;110;112;111;111;111 04:35:05 INFO - PID 1760 | |1;audio-beat-detection;140;111;111;150;112;111;109;109;131;108 04:35:05 INFO - PID 1760 | |2;audio-dft;182;184;184;187;183;182;205;167;251;269 04:35:05 INFO - PID 1760 | |3;audio-fft;79;60;59;90;85;89;76;58;73;61 04:35:05 INFO - PID 1760 | |4;audio-oscillator;84;70;88;70;69;68;84;68;68;82 04:35:05 INFO - PID 1760 | |5;imaging-gaussian-blur;86;114;86;85;86;86;86;86;86;86 04:35:05 INFO - PID 1760 | |6;imaging-darkroom;93;93;93;93;109;150;93;93;93;93 04:35:05 INFO - PID 1760 | |7;imaging-desaturate;85;89;84;112;137;130;84;83;83;84 04:35:05 INFO - PID 1760 | |8;json-parse-financial;61;60;60;60;61;60;62;62;60;62 04:35:05 INFO - PID 1760 | |9;json-stringify-tinderbox;57;63;67;62;61;57;57;59;57;58 04:35:05 INFO - PID 1760 | |10;stanford-crypto-aes;82;84;84;80;100;106;106;109;83;95 04:35:05 INFO - PID 1760 | |11;stanford-crypto-ccm;148;154;132;150;145;152;134;137;187;141 04:35:05 INFO - PID 1760 | |12;stanford-crypto-pbkdf2;163;219;185;184;186;185;189;180;210;181 04:35:05 INFO - PID 1760 | |13;stanford-crypto-sha256-iterative;68;67;68;68;68;68;68;67;69;69 04:35:05 INFO - PID 1760 | __end_tp_report 04:35:05 INFO - PID 1760 | __start_cc_report 04:35:05 INFO - PID 1760 | _x_x_mozilla_cycle_collect,2379 04:35:05 INFO - PID 1760 | __end_cc_report 04:35:05 INFO - PID 1760 | __startTimestamp1490528105618__endTimestamp 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | ------- Summary: start ------- 04:35:05 INFO - PID 1760 | Number of tests: 14 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:0.95 (0.9%) stddev-sans-first:0.78 04:35:05 INFO - PID 1760 | Values: 113.0 110.0 111.0 112.0 112.0 110.0 112.0 111.0 111.0 111.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#1] audio-beat-detection Cycles:10 Average:119.20 Median:111.50 stddev:15.30 (13.7%) stddev-sans-first:14.26 04:35:05 INFO - PID 1760 | Values: 140.0 111.0 111.0 150.0 112.0 111.0 109.0 109.0 131.0 108.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#2] audio-dft Cycles:10 Average:199.40 Median:185.50 stddev:33.48 (18.1%) stddev-sans-first:34.92 04:35:05 INFO - PID 1760 | Values: 182.0 184.0 184.0 187.0 183.0 182.0 205.0 167.0 251.0 269.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#3] audio-fft Cycles:10 Average:73.00 Median:77.50 stddev:12.77 (16.5%) stddev-sans-first:13.36 04:35:05 INFO - PID 1760 | Values: 79.0 60.0 59.0 90.0 85.0 89.0 76.0 58.0 73.0 61.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:76.00 stddev:8.25 (10.9%) stddev-sans-first:8.10 04:35:05 INFO - PID 1760 | Values: 84.0 70.0 88.0 70.0 69.0 68.0 84.0 68.0 68.0 82.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:86.00 stddev:8.90 (10.3%) stddev-sans-first:9.38 04:35:05 INFO - PID 1760 | Values: 86.0 114.0 86.0 85.0 86.0 86.0 86.0 86.0 86.0 86.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:93.00 stddev:18.17 (19.5%) stddev-sans-first:19.08 04:35:05 INFO - PID 1760 | Values: 93.0 93.0 93.0 93.0 109.0 150.0 93.0 93.0 93.0 93.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:84.00 stddev:21.14 (25.2%) stddev-sans-first:21.96 04:35:05 INFO - PID 1760 | Values: 85.0 89.0 84.0 112.0 137.0 130.0 84.0 83.0 83.0 84.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:61.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 04:35:05 INFO - PID 1760 | Values: 61.0 60.0 60.0 60.0 61.0 60.0 62.0 62.0 60.0 62.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:3.39 (5.7%) stddev-sans-first:3.44 04:35:05 INFO - PID 1760 | Values: 57.0 63.0 67.0 62.0 61.0 57.0 57.0 59.0 57.0 58.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:82.50 stddev:11.54 (14.0%) stddev-sans-first:11.55 04:35:05 INFO - PID 1760 | Values: 82.0 84.0 84.0 80.0 100.0 106.0 106.0 109.0 83.0 95.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:149.00 stddev:15.66 (10.5%) stddev-sans-first:16.61 04:35:05 INFO - PID 1760 | Values: 148.0 154.0 132.0 150.0 145.0 152.0 134.0 137.0 187.0 141.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:185.50 stddev:15.71 (8.5%) stddev-sans-first:13.77 04:35:05 INFO - PID 1760 | Values: 163.0 219.0 185.0 184.0 186.0 185.0 189.0 180.0 210.0 181.0 04:35:05 INFO - PID 1760 | 04:35:05 INFO - PID 1760 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 04:35:05 INFO - PID 1760 | Values: 68.0 67.0 68.0 68.0 68.0 68.0 68.0 67.0 69.0 69.0 04:35:05 INFO - PID 1760 | -------- Summary: end -------- 04:35:05 INFO - PID 1760 | 04:35:06 INFO - TEST-INFO | 1760: exit 0 04:35:06 INFO - TEST-OK | kraken | took 61512ms 04:35:06 INFO - SUITE-END | took 1182s 04:35:06 INFO - Completed test suite (00:19:42) 04:35:06 INFO - javascript benchmark 04:35:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7608.578760402712, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3473.287975964927, 3478.6521347865214, 3484.8606055757773, 3534.8586056557747, 3545.273218990107, 5996.16053752475, 6081.300650425203, 6222.471089957354, 6231.626102433852, 6240.968795156029, 4992.9750351248285, 6089.604175728575, 6092.634441933487, 6117.847053823645, 6136.171616831726, 5385.111456609665, 5434.972825135868, 5447.12845944649, 5492.286002819628, 5643.5767317451355, 5617.719114044302, 5619.971900140494, 5636.239107720467, 5676.687782171979, 5696.08862581987, 626.6332206347471, 5607.439256074401, 5609.467100625455, 5623.9437605623825, 5654.688992105433, 4935.161022626162, 5492.642978206443, 5532.004239236936, 5600.383957764642, 5631.746571404296, 5539.806106786243, 5554.222408862763, 5555.611107222496, 5559.110542313251, 5562.221289019541, 6119.479844213247, 6159.938400615982, 6196.225471816023, 6207.565470417073, 6236.095766113888, 6415.679216039226, 6468.838279043014, 6483.7730679425995, 6491.091247225392, 6500.414962653336, 6586.506012049068, 6653.101831252768, 6658.03458498515, 6674.766383176614, 6693.196816382017, 6288.434040936338, 6290.779822706229, 6292.779752708633, 6310.905336419957, 6314.968425157845, 6557.475401967831, 6601.636909969901, 6616.139901812733, 6746.156730408699, 6794.5583537070925, 6343.207099112611, 6449.903251451232, 6454.031895215755, 6457.3865482779065, 6460.483161347081, 6426.903596446057, 6641.369069938348, 6655.767048153291, 6664.400203981665, 6690.063391125246, 6330.05049242608, 6367.0, 6368.10846481493, 6387.297397286295, 6388.073729309317, 6405.711742971577, 6412.871742565216, 6550.6069635822005, 6591.736330546724, 6599.340065993362, 5313.07021271276, 5318.601104917147, 5325.281087053276, 5340.092184328673, 5354.839354819362, 4643.4660014098135, 4651.604613607814, 4662.650301227353, 4685.882852928704, 4708.246680531099, 6191.164192834, 6235.563510554309, 6248.625082495065, 6253.781117660951, 6256.874862502816, 6644.568103073285, 6651.235107962646, 6651.634160121143, 6655.700493477805, 6662.56693314933, 5325.360956685223, 6105.511559075263, 6125.632462052291, 6185.783497577563, 6220.34686357935, 5732.512736417368, 5752.309722833287, 5813.069908814654, 5852.180694702745, 5857.414258574108, 6782.830429239111, 6806.353396427331, 6807.353301436356, 6809.89785153233, 6812.18253809556, 6562.310957349409, 6565.244996825426, 6622.768203112868, 6630.071789949508, 6635.933640663531, 6566.0479230512265, 6648.069270302065, 6649.800505984828, 6717.764878229238, 6739.989001649792, 6517.869642607026, 6560.803175904731, 6581.111549940697, 6583.506237032338, 6586.769463068769, 6095.939040609537, 6268.247810262797, 6610.041543976193, 6633.535652504278, 6655.367740064686, 5391.514763671288, 6252.155958945484, 6308.558400911892, 6483.962565989419, 6524.510661700297, 6304.306526282014, 6405.775797847053, 6446.8388290293115, 6457.160569125984, 6461.547691661539, 6213.596116252429, 6269.31037585856, 6353.364663533795, 6376.585521941059, 6380.968095159495, 6259.65571893532, 6303.086052522451, 6402.647854367868, 6409.583377080475, 6438.484921206386, 6214.533909956862, 6334.556581039283, 6353.301136875032, 6361.363863613602, 6362.840928976812], "value": 6007.754223424609, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.3014334948306, 686.0806519264187, 707.3527722134247, 1113.1261959361898, 1128.7686024365005, 27615.2384761524, 28249.293767655792, 28285.858570707143, 37243.81378093106, 37745.24509509815, 41667.37498937518, 42080.57919420805, 42218.36672449916, 42259.78870105645, 42343.0, 8699.60851761669, 8864.51245181518, 8866.51234182123, 8878.378513504058, 8894.59974301158, 6147.077938309245, 6290.119383286344, 8476.66093356265, 8667.913320866774, 8684.696035638754, 8696.260817830493, 8735.606897689619, 8757.47455152693, 8761.868571971394, 8763.737087887373, 8631.223189912907, 8725.345599080094, 8726.432781869222, 8735.1701588349, 8743.431676940978, 8530.82938341236, 8624.956875215647, 8650.65397384104, 8671.0, 8733.170348816915, 39257.41113883294, 39384.0, 39432.40851387232, 39582.406263906065, 39607.0, 3037.179961410429, 3064.2952121012067, 3065.111117775865, 3079.0762771168784, 3079.491883839164, 28122.969257685578, 28333.291667708265, 28649.14052578426, 28759.137225883256, 29563.852180739173, 2783.44831696741, 2791.274268690155, 2791.9581206281923, 2812.2125804774696, 2897.058456001808, 327169.0, 327702.0, 330617.0, 331238.0, 331445.0, 51409.74295128501, 51532.484675153515, 51684.48315516872, 51749.741251293504, 51789.223161652604, 54576.0, 54579.72710136424, 54623.0, 54638.0, 54646.726766365915, 14500.0, 14742.926285368505, 14755.336009879582, 14759.262036898112, 14789.556313310402, 10038.397696138256, 11888.108391870644, 12015.459304331325, 12017.939910300393, 12034.338111403957, 11053.392063436428, 11239.381833999045, 11274.210805243718, 12560.0, 12761.61715148547, 4325.524192338903, 4412.646988240933, 4958.636449819894, 5163.302954101224, 5198.2722418861385, 874.4010352908278, 935.6117211357347, 986.4820968991337, 1008.4201584089031, 1013.8428543575758, 23104.306870793902, 23496.530069398515, 24888.626670599955, 24911.87544062268, 24977.750222497543, 1083.1767856429012, 1088.4068182840372, 1092.2900114925362, 1128.8306753986967, 1133.6372360844616, 12563.685907852378, 12630.684232894067, 12647.557335493213, 12660.683482913, 12720.363981800872, 409017.0, 410078.0, 410078.0, 410140.94929525163, 410340.0, 57606.42393576095, 57630.711846441336, 57792.0, 57805.265842024804, 58111.8377632445, 1442.935067921946, 1462.6782107936663, 1512.1154124836874, 1516.696660667871, 1519.8176218853807, 23918.52162956766, 28391.574126387706, 28560.0, 29089.854550727112, 29185.708142918298, 54037.0, 54387.72806136102, 54482.72758636181, 54505.0, 54509.454905452025, 377942.0, 378972.1051394725, 380339.0, 380620.0, 380781.0, 26362.868185659718, 33814.830925845214, 33890.49164262587, 34561.827190863885, 34616.82691586526, 33327.666723332455, 34064.48903266403, 39711.40432893567, 43607.34588981231, 43889.0, 28284.71715282821, 28482.430351392442, 29150.12549623515, 29969.850150749106, 30374.0, 26063.011014932585, 29325.411043433836, 29573.112806615834, 29899.55150672698, 30164.84917575398, 29505.85247073837, 36169.0, 36381.090472737334, 36596.45105323369, 36803.07992300214, 22069.33791986243, 31127.84436077805, 31653.733850646637, 31764.729410823307, 32100.35799284048, 21978.340649780534, 22597.531160474515, 22748.31755047351, 23874.16440424646, 24240.393990150387], "value": 17660.609157034014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.41332481227033, 427.1222637479979, 432.06889153873414, 436.87112301870957, 445.4009357414279, 20717.171313147457, 20833.479163020933, 21478.355649330526, 21780.346589602297, 22027.118915243413, 21239.93800309982, 22509.887450562765, 23407.82960851959, 23506.059757609717, 24147.879260603717, 6089.842929843327, 6303.401176888188, 6310.0, 6320.525960552954, 6322.74709011639, 6021.217241758565, 6263.749450022016, 6307.400796924285, 6313.747450101991, 6321.0, 6066.272047354324, 6257.843553911143, 6263.123162757218, 6267.71795269214, 6282.560220784547, 6259.749610015594, 6276.089966954812, 6297.338779428185, 6302.023186406114, 6306.635011870549, 5416.783328666848, 5512.586556008276, 5577.553795696334, 5604.915926261109, 5610.214569960209, 26803.597946030826, 28086.297842553897, 28217.435651286858, 28436.715632843614, 28730.138095857157, 2017.6482252627434, 2029.6853987631794, 2039.327022082724, 2070.1615845582414, 2120.1095539873136, 15071.849281507155, 15657.765133523006, 16122.83877161225, 16340.673186536322, 16814.243359048873, 1695.008420074258, 1713.31238729851, 1713.7943446786464, 1725.05984238589, 1726.3526177683368, 128507.35746321209, 142544.28727856293, 153807.2309638456, 165953.0, 167020.0, 9674.854877176847, 12094.460163365791, 12113.212641178296, 12149.878501215051, 12312.199707019194, 13366.198028118344, 15903.966242194452, 16231.51305460838, 16232.431864884673, 16235.675286494205, 7179.425645948311, 8713.738587842248, 8916.305901878623, 8969.461832290084, 9072.27421806254, 8575.27110195628, 8581.914180858112, 8612.827743445221, 8759.861218041613, 8775.956120219485, 7578.772636820904, 7718.498297610749, 7731.806704832312, 7751.224877512316, 7768.125793456457, 2097.538541520863, 2109.7362829646295, 2112.7570329412006, 2116.407405926343, 2287.885605719707, 778.0352363069753, 919.3610440743753, 919.5586118662945, 960.9087136722001, 964.2575217082807, 20574.58850822975, 21110.461250831144, 21144.89427552883, 21146.0, 21212.469688257916, 951.8001219743793, 951.9571619277149, 991.2169386184834, 997.2171845101706, 999.7500624843789, 10348.291527355035, 10773.299735517166, 10842.674719758419, 10876.72808179786, 10877.129829613612, 664357.0, 679022.0, 690873.0, 696249.0, 703657.9634203694, 79048.0, 84255.0, 141506.29246853906, 143689.0, 156530.0, 953.4041224234853, 1147.5008371358485, 1165.4813607944384, 1171.6367925942984, 1171.9589814356627, 20496.487587810425, 21238.0, 21566.0, 21758.020889060346, 22373.552528949655, 51051.74474127606, 53396.0, 60273.69863150656, 61127.91389420874, 61750.69124654348, 71002.0, 80846.19153808622, 82741.58629206815, 83309.0, 84219.5789021051, 8328.500289982621, 8570.357223208159, 8717.607707653298, 8794.120587941155, 8823.602937867683, 11198.16013798952, 13925.791113133513, 13930.442782288595, 14315.355808988828, 14456.13263204211, 11107.777844443228, 11509.597164099216, 11813.468393922121, 11878.940605296919, 12040.21738587024, 9649.951750241204, 10713.76791668968, 11195.88804111981, 11204.159688023594, 11510.194286399872, 10298.433586152834, 10452.895471045496, 10562.366258024544, 10583.206259530412, 10832.620858269922, 6155.753769849156, 7100.467464940047, 8584.828303433771, 8607.311415086653, 8941.418807777474, 9954.352967057119, 10549.470326802417, 11560.884391156318, 11577.7105572358, 11956.70108247266], "value": 10254.1521994251, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.8519562848085, 1415.6036309833244, 1431.8353389360227, 1432.5845504803608, 1436.6408397900527, 10423.947880260597, 10874.401907895062, 10893.891061089387, 10900.890991090097, 10903.83644245335, 10626.0, 10668.093212076983, 10895.782084358309, 11002.669919902395, 11006.834897476525, 2124.957500849982, 2455.680761501007, 2493.4015836199274, 2494.8004159667184, 2501.2746303571944, 2203.6584329428883, 2306.850054746436, 2313.0516488239823, 2315.235972129193, 2468.9135880244194, 2064.060852312202, 2143.742750869898, 2145.281330754199, 2148.892555372233, 2179.7384313882358, 2103.663413853784, 2131.7228760261223, 2385.582523058458, 2472.863992480413, 2476.319012271622, 2449.546833835728, 2456.8403053801626, 2457.1522824625476, 2458.0782206672498, 2460.5694003549306, 10880.12958963281, 10971.725706857393, 10974.945125274322, 10977.06694930932, 10990.120790336754, 5468.7265636718, 5662.8301150965535, 5683.772649094031, 6124.5100391968535, 6196.10156527301, 3902.512185976753, 3968.7023473239333, 4042.0, 4063.451434056394, 4071.653909417703, 1186.9643910682694, 1188.3345326617123, 1193.6419074277683, 1199.1006744941294, 1224.253205544617, 15142.54572362831, 15174.620634484116, 15229.086254824746, 15265.313060912396, 15279.923600382039, 5147.6911385317, 5353.839384818461, 5429.131338985743, 5435.266239057755, 5463.781448742086, 11268.211225214156, 11499.310041397543, 11505.942470287762, 11522.251053681484, 11530.250533715282, 6261.0, 6318.4313411793155, 6320.273168585672, 6324.0, 6328.0, 5721.5136713379825, 5920.526357891358, 5930.9406905931255, 5975.19334889793, 6012.428819262163, 4103.076807718299, 4209.5579964103945, 4234.661227101825, 4282.614564689131, 4284.421603083606, 1264.64589914824, 1265.471828893957, 1278.4758249117817, 1278.4949944771763, 1282.7178020835402, 722.1298335505703, 755.5995322479095, 757.1519897714485, 779.2168870285441, 785.5090568394753, 5903.26209223847, 5975.282966044103, 6018.458338749533, 6064.75740970365, 6077.088436734525, 562.361719448429, 575.32686756495, 588.6791698524314, 597.2892258212713, 598.7395482964924, 3820.770753754728, 4089.9795501022304, 4216.557261487561, 4240.109576988805, 4252.298370768788, 13439.462421503225, 14110.85889141116, 14131.71736565263, 14149.43402263898, 14169.149851008973, 13246.801297980537, 13809.585712428643, 14089.225092620005, 14089.929550352388, 14104.730574248368, 9937.65218217359, 10206.591736330613, 10211.438370889524, 10234.59061637541, 10280.537375818107, 5531.612787105025, 6597.571157874723, 6621.172353455818, 6868.278830722702, 6874.415674667709, 12095.0, 12457.377131143225, 12476.126671132934, 12498.37508124572, 12739.681507962376, 12728.809067863804, 12860.935695321463, 12998.480060797463, 13014.739705205653, 13028.674283142997, 9020.0, 9166.923512860729, 9339.813203736023, 9349.532523373668, 9355.953220233856, 10207.846882296622, 10259.127974121984, 10349.74125646865, 10369.429681367597, 10370.792584148125, 8525.701600443954, 8687.435316704394, 8790.736277911672, 8841.336899732418, 8867.0, 8461.703840365557, 8477.406581539233, 8478.491290522588, 8566.18621230982, 8584.91415085841, 7343.889841652486, 7731.497452665559, 7839.137694853447, 7847.450678452454, 7858.096318923397, 4820.71075735457, 5093.567046801063, 5673.8865222694485, 5728.0, 5954.732037058429, 5518.503334699896, 5987.161797348462, 6010.849728756816, 6067.544934130046, 6192.133101365723], "value": 5083.27986861875, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.3234836081939, 399.7002248313765, 400.7340177552944, 410.2062509044998, 410.2246753635627, 5099.923501147486, 5509.889802203958, 5812.564057695668, 6092.969535152323, 6138.987067133923, 5872.588918775677, 5934.6647004423985, 6069.757209711606, 6076.9392306076925, 6141.539384546166, 4733.715977041388, 4736.9920100735935, 4746.477887432379, 4754.595859351959, 4754.881127971811, 4774.092922344761, 4777.90444191116, 4786.497417771118, 4793.010900476298, 4796.952030479685, 3489.4242449995722, 3856.2865869814173, 4195.489848412953, 4517.560517320643, 4676.205045142334, 3844.365679662866, 4678.812847486113, 4706.788194531255, 4709.740964246965, 4721.244600863845, 3608.3324584951606, 4181.937270940938, 4188.204241194163, 4190.434291370657, 4199.076203235283, 5095.79616815327, 5119.436861945183, 5429.592780541475, 5844.590878638497, 5916.0, 5473.370562385337, 5495.780168793243, 5499.312585926759, 5500.4499550045075, 5502.477264659851, 5207.0, 6064.9689552776135, 6516.902246466307, 6581.012848072827, 6584.670766461706, 3805.8858234253016, 3944.2900277949957, 4022.6982976276895, 4576.313552967048, 4663.60359369454, 5598.776048958037, 5922.763089476459, 6553.3446655334565, 6766.0, 6871.622060786704, 3911.706622003361, 4118.485189351331, 4392.363107349416, 4483.327500874895, 4567.200739870509, 4187.748735075906, 4373.053990973986, 4801.663883528119, 4802.51974802517, 4851.757412129379, 4739.691920025188, 5469.890602188014, 5490.615656903979, 5493.752781124859, 5512.6692398456225, 5138.38339399267, 5221.0, 6364.109024736541, 6471.773487927994, 6560.704768285439, 1640.4668482743157, 1663.717168081429, 1663.9168041598114, 1670.991645041767, 1674.6818104560095, 1111.3554138599593, 1143.114086582905, 1151.8099513580166, 1152.2395219155317, 1169.6374124021581, 5424.83725488236, 6085.2393450818645, 6143.96928015357, 6155.876882462415, 6162.7843025494785, 5901.967155747727, 6522.510811689142, 6769.239997600659, 6800.607429016807, 6809.965950170217, 4603.608693261043, 4846.3699719037, 5364.585936328668, 5836.795712150054, 6117.296510901214, 2919.2118128105717, 3455.8617655293506, 3483.7561370704316, 3511.736619753529, 3521.841517131735, 6060.484858787053, 6510.446612037935, 6833.813179782522, 6919.37725604698, 6952.652367381611, 5186.858891043964, 5748.511376533042, 6589.80230593083, 6934.133233345832, 6966.616836073963, 5294.279359208195, 6577.835554111186, 6826.692798823966, 6889.621070841152, 6931.272216417305, 6627.801165965029, 6699.464042876461, 6704.966475167593, 6706.8993965089585, 6717.764878229238, 5797.507211887037, 6456.644884531396, 6660.101870966751, 6664.166979127609, 6701.564398314094, 5687.516561092353, 6782.898256526255, 6872.209695884837, 6901.378875901193, 6916.827079322856, 4161.500619925629, 4743.45450273223, 4764.30917516965, 4766.904661906673, 4781.665283430127, 5680.318361796611, 6405.686834198885, 6632.535722499379, 6669.966650166718, 6781.42357899584, 5937.643741375531, 6445.452136568444, 6496.610203387811, 6501.9674901625185, 6511.79531786621, 5871.735771890189, 6213.7009306556865, 6382.32985536512, 6396.71214795326, 6535.901961470487, 6363.0, 6388.968055159695, 6390.265119511129, 6391.392817682313, 6415.615063096229, 5781.508571771446, 6343.794679010974, 6506.4469520091325, 6512.120863683342, 6530.68974476843, 5583.55331573465, 5605.0, 5978.940210597838, 6288.968555157195, 6450.419462248233], "value": 4751.092870499666, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.9999999999999, 728.3735987051139, 740.93702035327, 741.8850078237872, 769.5651956644492, 15365.154916479603, 15702.136382498973, 16010.519684409486, 16010.839891601081, 16049.11729854859, 14974.775378369333, 15089.698206035873, 15092.698146037073, 15115.01752386094, 15124.773128403083, 820.885076089345, 913.1735779119907, 923.9769005774842, 933.9066093390641, 967.762898089969, 962.4850704872895, 969.2633598465146, 975.2100798353321, 978.8238117138912, 980.7352014955958, 1005.5776573839005, 1008.1027885182194, 1009.9949500252525, 1010.0303708439907, 1014.4217795856365, 1001.5092604623718, 1001.884783249921, 1002.4336250018716, 1002.4686915934566, 1005.8440941654019, 743.8475112601903, 745.5824738146655, 745.7501736918139, 746.3655892491394, 748.2704363245846, 15307.234638268154, 15397.307121179574, 15543.922280388639, 16798.58003549909, 16857.325706971707, 13394.933025334909, 13855.891528677681, 14558.199299038548, 14630.0, 14982.475613353481, 11712.238704484267, 11724.53784705213, 11913.22564033335, 11986.580469683606, 12214.694632634166, 2746.835189888613, 3060.0360886320736, 3066.877324907023, 3088.4440800655875, 3137.074563003919, 95927.52036239774, 114294.57023301831, 114525.58781470974, 117981.41009294984, 118260.0, 10783.83824242637, 11410.600628977945, 11595.130365222642, 11930.582429614922, 12145.939270303592, 25005.249842504752, 25370.0, 25399.492010159694, 25560.616590751153, 25577.744222557907, 7876.84246315082, 7943.086545047278, 7995.400344974034, 7995.840083198304, 8013.11855695873, 4154.896127596834, 4194.902221472584, 4220.198162349144, 4235.7670328131635, 4236.4492615959725, 4111.09555897702, 4148.170365926827, 4187.0, 4232.111256636079, 4254.893627659333, 797.2426195114664, 802.4864086984335, 805.5690205740018, 809.7608473178525, 827.5034979012544, 501.9222020586815, 549.2941570082425, 562.8846086552247, 586.1939832729996, 602.2652364115771, 9605.115268376723, 9909.856008934661, 9941.900580994243, 10035.79928401428, 10106.0, 555.4362322242927, 588.2499812738793, 592.5467017731439, 610.2646311194999, 618.5082859127004, 3905.1478761957324, 4488.483824360175, 4700.341952126705, 4815.975920120377, 5125.121742760027, 91757.54121229485, 98809.01190987998, 99838.5008074955, 102676.0, 105362.47318763357, 62001.0, 88075.0, 88452.0, 88560.0, 90406.0, 48196.0, 51840.74079629578, 51861.222081668806, 52133.478665213624, 58345.708271458374, 9381.437113773196, 9962.352447090916, 10472.476376181314, 10596.364218146937, 10738.355698658106, 26205.213843584723, 28048.579271311355, 34630.13424664303, 35793.28413431769, 36400.0, 49827.50172498373, 50257.49742502675, 50280.74859625825, 50341.993160137325, 50800.0, 13125.934370328469, 13400.329983500591, 13414.530491432753, 13864.930675346559, 14266.001379903308, 14279.64300892486, 14287.285635717964, 14347.354369053626, 14403.135811851322, 14453.927730361282, 13450.394732237268, 13481.0, 13579.321033948461, 13595.45618175262, 13730.352357717138, 12973.286469244282, 13043.869561304646, 13427.530036448677, 13829.930850345683, 14116.153030818185, 13239.801402979156, 13284.468621255428, 13323.66690832737, 13559.525416610371, 13734.0, 8772.91227087721, 9738.853917191389, 9752.414855108716, 9772.951135244279, 10023.296039673127, 8201.0, 9077.409968352036, 10113.0, 10182.23633227496, 11027.964552257406], "value": 7383.013973940454, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1481.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 111.0, 112.0, 112.0, 110.0, 112.0, 111.0, 111.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 111.0, 150.0, 112.0, 111.0, 109.0, 109.0, 131.0, 108.0], "value": 119.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 184.0, 187.0, 183.0, 182.0, 205.0, 167.0, 251.0, 269.0], "value": 199.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 59.0, 90.0, 85.0, 89.0, 76.0, 58.0, 73.0, 61.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 88.0, 70.0, 69.0, 68.0, 84.0, 68.0, 68.0, 82.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 114.0, 86.0, 85.0, 86.0, 86.0, 86.0, 86.0, 86.0, 86.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 109.0, 150.0, 93.0, 93.0, 93.0, 93.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 84.0, 112.0, 137.0, 130.0, 84.0, 83.0, 83.0, 84.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 61.0, 60.0, 62.0, 62.0, 60.0, 62.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 67.0, 62.0, 61.0, 57.0, 57.0, 59.0, 57.0, 58.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 84.0, 80.0, 100.0, 106.0, 106.0, 109.0, 83.0, 95.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 154.0, 132.0, 150.0, 145.0, 152.0, 134.0, 137.0, 187.0, 141.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 219.0, 185.0, 184.0, 186.0, 185.0, 189.0, 180.0, 210.0, 181.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 68.0, 68.0, 68.0, 67.0, 69.0, 69.0], "value": 68.0, "unit": "score"}]}]} 04:35:06 INFO - Return code: 0 04:35:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:35:06 ERROR - # TBPL SUCCESS # 04:35:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:35:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:35:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:35:06 INFO - 2017-03-26 04:35:06.945 screenresolution[1764:16741] starting screenresolution argv=screenresolution get 04:35:06 INFO - 2017-03-26 04:35:06.948 screenresolution[1764:16741] Display 0: 1600x1200x32@60 04:35:06 INFO - 2017-03-26 04:35:06.955 screenresolution[1765:16744] starting screenresolution argv=screenresolution list 04:35:06 INFO - Available Modes on Display 0 04:35:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:35:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:35:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:35:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:35:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:35:07 INFO - Intel Iris: 04:35:07 INFO - Chipset Model: Intel Iris 04:35:07 INFO - Type: GPU 04:35:07 INFO - Bus: Built-In 04:35:07 INFO - VRAM (Dynamic, Max): 1536 MB 04:35:07 INFO - Vendor: Intel (0x8086) 04:35:07 INFO - Device ID: 0x0a2e 04:35:07 INFO - Revision ID: 0x0009 04:35:07 INFO - Displays: 04:35:07 INFO - Display: 04:35:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:35:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:35:07 INFO - Display Serial Number: 1600x1200 60 04:35:07 INFO - Main Display: Yes 04:35:07 INFO - Mirror: Off 04:35:07 INFO - Online: Yes 04:35:07 INFO - Rotation: Supported 04:35:07 INFO - Return code: 0 04:35:07 INFO - Running post-action listener: _resource_record_post_action 04:35:07 INFO - [mozharness: 2017-03-26 11:35:07.200668Z] Finished run-tests step (success) 04:35:07 INFO - Running post-run listener: _resource_record_post_run 04:35:07 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 33885184; Write bytes: 517445632; Read time: 316; Write time: 1743 04:35:07 INFO - TinderboxPrint: CPU usage
53.3% 04:35:07 INFO - TinderboxPrint: I/O read bytes / time
33,885,184 / 316 04:35:07 INFO - TinderboxPrint: I/O write bytes / time
517,445,632 / 1,743 04:35:07 INFO - TinderboxPrint: CPU idle
2,245.8 (46.7%) 04:35:07 INFO - TinderboxPrint: CPU system
958.4 (19.9%) 04:35:07 INFO - TinderboxPrint: CPU user
1,609.6 (33.4%) 04:35:07 INFO - TinderboxPrint: Swap in / out
240,848,896 / 0 04:35:07 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207151616; Write bytes: 204894208; Read time: 15702; Write time: 387 04:35:07 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 31894528; Write bytes: 301506560; Read time: 292; Write time: 1319 04:35:07 INFO - Running post-run listener: _upload_blobber_files 04:35:07 INFO - Blob upload gear active. 04:35:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:35:07 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 04:35:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:35:10 INFO - (blobuploader) - INFO - Open directory for files ... 04:35:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:35:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:35:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:35:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:35:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:35:12 INFO - (blobuploader) - INFO - Done attempting. 04:35:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:35:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:35:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:35:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:35:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:35:14 INFO - (blobuploader) - INFO - Done attempting. 04:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:35:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:35:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:35:16 INFO - (blobuploader) - INFO - Done attempting. 04:35:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:35:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:35:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:35:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:35:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:35:20 INFO - (blobuploader) - INFO - Done attempting. 04:35:20 INFO - (blobuploader) - INFO - Iteration through files over. 04:35:20 INFO - Return code: 0 04:35:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:35:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:35:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15d59a54c7f06510b597a24782342914772d7791a96a509ddd4e0aab35cb81c5d9044b479812f8255e6c6fcf0a4a414f15ab128dda3418e232fd52818df9e02d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1137bf44dbc2f0f77722a88756ec8ba9b2db3fa6fe2d87db9edbbc75c59be2e3a7d52597451be9a1ab4fca0789550cbeaf60c09ea99772eb24dedd6b5222f985", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f43bd2dfebd0a9cd09e79aec50cb5c8b90bf169892ed9abe31bb939f986d90b5ab71e89e2e8eee123c98a6c8c5af7749f437a34c833e4e5eb96aaa36cecad3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf24ec8ce4f685d48a7ba25ca53a5d59c7338637725e65f2ae5bf15c75ba2c9b8ecee9888c95eded6a01c974c4951b430afd835e170129987d90950e8dc2b703"} 04:35:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:35:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:35:20 INFO - Contents: 04:35:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15d59a54c7f06510b597a24782342914772d7791a96a509ddd4e0aab35cb81c5d9044b479812f8255e6c6fcf0a4a414f15ab128dda3418e232fd52818df9e02d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1137bf44dbc2f0f77722a88756ec8ba9b2db3fa6fe2d87db9edbbc75c59be2e3a7d52597451be9a1ab4fca0789550cbeaf60c09ea99772eb24dedd6b5222f985", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f43bd2dfebd0a9cd09e79aec50cb5c8b90bf169892ed9abe31bb939f986d90b5ab71e89e2e8eee123c98a6c8c5af7749f437a34c833e4e5eb96aaa36cecad3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf24ec8ce4f685d48a7ba25ca53a5d59c7338637725e65f2ae5bf15c75ba2c9b8ecee9888c95eded6a01c974c4951b430afd835e170129987d90950e8dc2b703"} 04:35:20 INFO - Running post-run listener: copy_logs_to_upload_dir 04:35:20 INFO - Copying logs to upload dir... 04:35:20 INFO - mkdir: /builds/slave/test/build/upload/logs 04:35:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.330412 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2017-03-26 04:35:20.198238) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 04:35:20.202321) ========= 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.JmcZWozinP/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.MMyUEE3rDr/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15d59a54c7f06510b597a24782342914772d7791a96a509ddd4e0aab35cb81c5d9044b479812f8255e6c6fcf0a4a414f15ab128dda3418e232fd52818df9e02d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1137bf44dbc2f0f77722a88756ec8ba9b2db3fa6fe2d87db9edbbc75c59be2e3a7d52597451be9a1ab4fca0789550cbeaf60c09ea99772eb24dedd6b5222f985", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f43bd2dfebd0a9cd09e79aec50cb5c8b90bf169892ed9abe31bb939f986d90b5ab71e89e2e8eee123c98a6c8c5af7749f437a34c833e4e5eb96aaa36cecad3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf24ec8ce4f685d48a7ba25ca53a5d59c7338637725e65f2ae5bf15c75ba2c9b8ecee9888c95eded6a01c974c4951b430afd835e170129987d90950e8dc2b703"} build_url:https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/S1qFSxWJTBezroXd1Mxxug/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15d59a54c7f06510b597a24782342914772d7791a96a509ddd4e0aab35cb81c5d9044b479812f8255e6c6fcf0a4a414f15ab128dda3418e232fd52818df9e02d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1137bf44dbc2f0f77722a88756ec8ba9b2db3fa6fe2d87db9edbbc75c59be2e3a7d52597451be9a1ab4fca0789550cbeaf60c09ea99772eb24dedd6b5222f985", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f43bd2dfebd0a9cd09e79aec50cb5c8b90bf169892ed9abe31bb939f986d90b5ab71e89e2e8eee123c98a6c8c5af7749f437a34c833e4e5eb96aaa36cecad3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf24ec8ce4f685d48a7ba25ca53a5d59c7338637725e65f2ae5bf15c75ba2c9b8ecee9888c95eded6a01c974c4951b430afd835e170129987d90950e8dc2b703"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 04:35:20.241444) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 04:35:20.241864) ========= 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.JmcZWozinP/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.MMyUEE3rDr/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005408 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 04:35:20.290823) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 04:35:20.291165) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 04:35:20.291608) ========= ========= Total master_lag: 0.21 =========