Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.09.2021 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15838856 kB
MemAvailable 30793940 kB
Buffers 0 kB
Cached 15887884 kB
SwapCached 2964 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-29 10:37:10
DTS_END                         2021-09-29 10:37:10.1430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34420
TRN_ID                          234026
ATT_ID                          679
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9149.12 23150 1097894
customer order: creation 166.83 420 20019
customer order: refuse 14.30 313 1716
order to supplier: creation 45.20 1492 5424
order to supplier: removal 3.62 113703 434
invoice (draft): creation 45.04 3553 5405
invoice (draft): removal 52.21 1098 6265
invoice accept: apply 40.80 17667 4896
invoice accept: cancel 46.68 821 5601
customer reserve: creation 3836.18 169 460341
customer reserve: removal 4609.72 68 553166
realization accept: apply 81.37 194 9764
realization accept: cancel 82.81 123 9937
payment from customer: creation 57.13 119 6856
payment from customer: removal 5.64 68 677
payment to supplier: creation 47.51 139 5701
payment to supplier: removal 3.54 71 425
service: total inventory turnovers 3.85 3719 462
service: total monetary turnovers 5.95 472 714
service: aggregate perf. data 0.73 20688 87
service: refresh index statistics 0.03 298093 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-09-2021 10:38:54 to 29-09-2021 10:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7672
TEST_TIME 2 5410
TEST_TIME 3 4336
TEST_TIME 4 5073
TEST_TIME 5 5090
TEST_TIME 6 4850
TEST_TIME 7 4898
TEST_TIME 8 4977
TEST_TIME 9 5081
TEST_TIME 10 5312
TEST_TIME 11 5457
TEST_TIME 12 5933
TEST_TIME 13 6204
TEST_TIME 14 6260
TEST_TIME 15 6897
TEST_TIME 16 7189
TEST_TIME 17 7265
TEST_TIME 18 7506
TEST_TIME 19 7655
TEST_TIME 20 8247
TEST_TIME 21 8707
TEST_TIME 22 8989
TEST_TIME 23 9011
TEST_TIME 24 9736
TEST_TIME 25 11037
TEST_TIME 26 11036
TEST_TIME 27 11060
TEST_TIME 28 10848
TEST_TIME 29 10903
TEST_TIME 30 10890
TEST_TIME 31 10682
TEST_TIME 32 10613
TEST_TIME 33 10329
TEST_TIME 34 10499
TEST_TIME 35 10300
TEST_TIME 36 10173
TEST_TIME 37 10087
TEST_TIME 38 10063
TEST_TIME 39 10024
TEST_TIME 40 9989
TEST_TIME 41 9838
TEST_TIME 42 9856
TEST_TIME 43 9796
TEST_TIME 44 9808
TEST_TIME 45 9711
TEST_TIME 46 9632
TEST_TIME 47 9516
TEST_TIME 48 9526
TEST_TIME 49 9491
TEST_TIME 50 9478
TEST_TIME 51 9457
TEST_TIME 52 9416
TEST_TIME 53 9434
TEST_TIME 54 9442
TEST_TIME 55 9390
TEST_TIME 56 9371
TEST_TIME 57 9477
TEST_TIME 58 9630
TEST_TIME 59 9645
TEST_TIME 60 9667
TEST_TIME 61 9584
TEST_TIME 62 9453
TEST_TIME 63 9391
TEST_TIME 64 9482
TEST_TIME 65 9398
TEST_TIME 66 9377
TEST_TIME 67 9315
TEST_TIME 68 9304
TEST_TIME 69 9271
TEST_TIME 70 9334
TEST_TIME 71 9335
TEST_TIME 72 9438
TEST_TIME 73 9411
TEST_TIME 74 9345
TEST_TIME 75 9449
TEST_TIME 76 9506
TEST_TIME 77 9515
TEST_TIME 78 9589
TEST_TIME 79 9484
TEST_TIME 80 9394
TEST_TIME 81 9366
TEST_TIME 82 9351
TEST_TIME 83 9280
TEST_TIME 84 9211
TEST_TIME 85 9183
TEST_TIME 86 9183
TEST_TIME 87 9235
TEST_TIME 88 9195
TEST_TIME 89 9169
TEST_TIME 90 9235
TEST_TIME 91 9254
TEST_TIME 92 9226
TEST_TIME 93 9292
TEST_TIME 94 9348
TEST_TIME 95 9322
TEST_TIME 96 9308
TEST_TIME 97 9318
TEST_TIME 98 9392
TEST_TIME 99 9321
TEST_TIME 100 9237
TEST_TIME 101 9220
TEST_TIME 102 9199
TEST_TIME 103 9181
TEST_TIME 104 9122
TEST_TIME 105 9088
TEST_TIME 106 9095
TEST_TIME 107 9077
TEST_TIME 108 9061
TEST_TIME 109 9080
TEST_TIME 110 9066
TEST_TIME 111 9035
TEST_TIME 112 9064
TEST_TIME 113 9172
TEST_TIME 114 9243
TEST_TIME 115 9293
TEST_TIME 116 9269
TEST_TIME 117 9301
TEST_TIME 118 9258
TEST_TIME 119 9229
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-09-2021 10:38:57 to 29-09-2021 10:39:01.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1523464 1523464 0 0.00 0 23727 7 0 0
doc_list_biud 1523462 1523462 0 0.00 0 57023 32 0 0
sp_add_invoice_to_stock 4971 4896 75 1.50 29 256977 17667 0 75
sp_cancel_adding_invoice 5601 5601 0 0.00 4 69783 822 0 0
sp_cancel_client_order 1716 1716 0 0.00 4 8305 314 0 0
sp_cancel_customer_reserve 553166 553166 0 0.00 1 25334 69 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 2965 68 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 4706 71 0 0
sp_cancel_supplier_invoice 6274 6265 9 0.14 2 23290 1098 0 9
sp_cancel_supplier_order 434 434 0 0.00 10 1232443 113704 0 0
sp_cancel_write_off 9937 9937 0 0.00 1 9545 123 0 0
sp_client_order 20019 20019 0 0.00 4 17529 420 0 0
sp_customer_reserve 460341 460341 0 0.00 0 98296 170 0 0
sp_fill_shopping_cart 30827 30827 0 0.00 1 37603 48 0 0
sp_fill_shopping_cart_clo_res 460334 460334 0 0.00 0 22363 17 0 0
sp_get_clo_for_invoice 4885 4885 0 0.00 0 36269 404 0 0
sp_kill_cost_storno 16640 16640 0 0.00 0 9328 59 0 0
sp_kill_qstorno_ret_qs2qd 1122968 1122968 0 0.00 0 25644 38 0 0
sp_kill_qty_storno 591768 591768 0 0.00 0 57023 81 0 0
sp_lock_selected_doc 20318 20318 0 0.00 0 10120 4 0 0
sp_make_cost_storno 27206 27206 0 0.00 0 22879 62 0 0
sp_multiply_rows_for_pdistr 27206 27206 0 0.00 0 13144 36 0 0
sp_multiply_rows_for_qdistr 20019 20019 0 0.00 0 17509 242 0 0
sp_pay_from_customer 6856 6856 0 0.00 4 13152 119 0 0
sp_pay_to_supplier 5701 5701 0 0.00 4 25634 139 0 0
sp_payment_common 12557 12557 0 0.00 1 10818 22 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 8257 277 0 0
sp_qd_handle_on_invoice_upd_sts 10486 10486 0 0.00 0 57023 372 0 0
sp_qd_handle_on_reserve_upd_sts 9764 9764 0 0.00 0 15320 54 0 0
sp_reserve_write_off 9764 9764 0 0.00 7 19245 194 0 0
sp_supplier_invoice 5405 5405 0 0.00 7 75943 3553 0 0
sp_supplier_order 5424 5424 0 0.00 6 39370 1492 0 0
srv_aggregate_perf_data 87 87 0 0.00 2993 297501 20688 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1027 137662 45380 0 0
srv_find_qd_qs_mism 1050800 1050800 0 0.00 0 19571 5 0 0
srv_make_invnt_saldo 462 462 0 0.00 61 78712 3719 0 0
srv_make_money_saldo 714 714 0 0.00 1 11535 472 0 0
srv_recalc_idx_stat 4 4 0 0.00 198094 397947 298094 0 0
t$perf-abend:sp_add_invoice_to_stock 75 75 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4896 4896 0 0.00 0 8569 144 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 3062 77 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 3137 15 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 1838 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 763 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 7623 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 4450 20 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 21039 1481 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 996 4 0 0
t$perf-norm:sp_client_order 20019 20019 0 0.00 0 29440 19 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 5096 13 0 0
t$perf-norm:sp_pay_from_customer 6856 6856 0 0.00 0 10029 11 0 0
t$perf-norm:sp_pay_to_supplier 5701 5701 0 0.00 0 3057 8 0 0
t$perf-norm:sp_reserve_write_off 9764 9764 0 0.00 0 4472 7 0 0
t$perf-norm:sp_supplier_invoice 5405 5405 0 0.00 0 10574 22 0 0
t$perf-norm:sp_supplier_order 5424 5424 0 0.00 0 2693 13 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 210 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 375 19 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 5257 22 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 4514 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9920 9770 150 1.51 0 7370 17 0 150
v_all_customers 35988 35988 0 0.00 0 105 1 0 0
v_all_suppliers 30132 30132 0 0.00 0 105 1 0 0
v_all_wares 40038 40038 0 0.00 0 105 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 2178 14 0 0
v_cancel_client_order 3432 3432 0 0.00 0 5869 10 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 2918 11 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 1557 6 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 4690 10 0 0
v_cancel_supplier_invoice 828 810 18 2.17 0 3937 27 0 18
v_cancel_supplier_order 868 868 0 0.00 0 533 3 0 0
v_cancel_write_off 1912 1912 0 0.00 0 4945 21 0 0
v_max_id_clo_ord 5417 5417 0 0.00 0 1070 2 0 0
v_max_id_clo_res 977 977 0 0.00 0 451 3 0 0
v_max_id_ord_sup 5390 5390 0 0.00 0 6007 8 0 0
v_max_non_paid_invoice 1442 1442 0 0.00 0 2367 5 0 0
v_max_non_paid_realizn 3737 3737 0 0.00 0 5413 4 0 0
v_min_id_clo_ord 5417 5417 0 0.00 0 3433 8 0 0
v_min_id_clo_res 977 977 0 0.00 0 2111 7 0 0
v_min_id_ord_sup 5390 5390 0 0.00 0 5476 9 0 0
v_min_non_paid_invoice 5701 5701 0 0.00 0 10809 15 0 0
v_min_non_paid_realizn 6856 6856 0 0.00 0 4426 13 0 0
v_our_firm 10288 10288 0 0.00 0 111 1 0 0
v_reserve_write_off 19528 19528 0 0.00 0 6629 8 0 0
x_lock_depdocs_on_canc_invoice 11461 11461 0 0.00 0 22426 114 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 21764 334 0 0
x_make_qstorno_1000_1200 5424 5424 0 0.00 2 39362 1413 0 0
x_make_qstorno_1200_2000 5405 5405 0 0.00 3 71679 3361 0 0
x_make_qstorno_2100_3300 460341 460341 0 0.00 0 98294 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-09-2021 10:39:02 to 29-09-2021 10:39:06.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 18357 8192000000 9706336480 10000224256 786764336 8.762 100 85 170 0 815429600 7419328 21245936
08:09:40 14536 8192000000 9750962992 10052390912 822513184 9.124 100 86 172 0 854724464 11395648 20815632
08:11:00 19484 8192000000 9791039232 10108346368 863248080 9.533 100 76 152 1 896507952 15912288 17347584
08:12:24 11809 8192000000 9855010272 10188443648 916289328 10.059 100 80 160 0 969086704 35460080 17337296
08:13:37 9327 8192000000 9913256608 10255851520 957896032 10.468 100 77 152 1 1019024576 45603200 15525344
08:14:49 5096 8192000000 9982271664 10336673792 998086096 10.860 100 83 165 1 1088160176 73316464 16757616
08:15:56 3146 8192000000 10014266208 10379022336 1018972928 11.062 100 83 165 0 1133212352 98224608 16014816
08:17:01 2064 8192000000 10017641232 10384105472 1048127504 11.343 100 90 177 1 1132008080 65667152 18213424
08:18:04 2436 8192000000 10019690512 10392068096 1072236448 11.573 100 95 189 1 1143821072 52115472 19469152
08:19:09 1920 8192000000 10035400624 10414034944 1075512480 11.605 100 92 187 1 1145393872 50535696 19345696
08:20:17 4720 8192000000 10008722016 10414780416 1079236992 11.640 100 31 62 0 1127071184 41698992 6135200
08:21:22 6762 8192000000 9983917472 10394247168 1083832064 11.684 100 22 44 1 1104113296 15315488 4965744
08:22:30 5514 8192000000 10008966368 10410975232 1089878272 11.742 100 40 79 0 1124991936 26031696 9081968
08:23:39 37316 8192000000 10015349440 10428280832 1096280384 11.802 100 20 40 1 1127216992 27086112 3850496
08:25:17 5384 8192000000 10019088368 10422009856 1107880704 11.912 100 43 86 2 1128323840 11847904 8595232
08:26:24 5955 8192000000 10013246416 10413068288 1087365696 11.718 99 48 98 0 1109478608 11107456 11005456
08:27:32 53760 8192000000 9948141600 10340446208 956878368 10.458 89 19 38 0 969436496 8532080 4026048
08:29:28 3178 8192000000 9960707120 10339614720 1041931440 11.283 100 72 145 2 1069886096 11841504 16113152
08:30:32 9563 8192000000 9937366576 10316857344 992491408 10.806 96 24 46 1 1018086576 20784144 4811024
08:31:42 4120 8192000000 9941145168 10326446080 1033001168 11.197 100 36 72 0 1058857488 17428784 8427536
08:32:47 51554 8192000000 9942118960 10345947136 1005788784 10.935 96 23 45 0 1025011872 14714768 4508320
08:34:39 6541 8192000000 9983184128 10382979072 1059762752 11.454 100 47 89 0 1081873200 12019664 10090784
08:35:48 10425 8192000000 9998414352 10398334976 1068284112 11.536 100 24 47 2 1097935520 24430336 5221072
08:36:58 12190 8192000000 10020715328 10428579840 1081604224 11.663 100 31 62 0 1122324736 34719536 6000976
08:38:15 60141 8192000000 10036231936 10450116608 1109054544 11.923 100 40 76 1 1157386016 40061008 8270464
08:40:27 19123 8192000000 10082305136 10490515456 1121016256 12.037 100 53 105 1 1192202688 60489120 10697312
08:41:50 10732 8192000000 10090044608 10503159808 1136172192 12.180 100 36 71 1 1204802960 60897520 7733248
08:43:04 29831 8192000000 10136024896 10555748352 1150764896 12.317 100 35 68 0 1249325872 90406976 8154000
08:44:40 11895 8192000000 10209283888 10634772480 1159575456 12.399 100 46 92 1 1328513568 158041376 10896736
08:45:59 7918 8192000000 10248043600 10665328640 1163219120 12.433 100 53 106 0 1359029808 184094912 11715776
08:47:12 7557 8192000000 10312216512 10722607104 1178816960 12.579 100 75 149 1 1408413792 213570864 16025968
08:48:23 8901 8192000000 10329808256 10755846144 1190597264 12.689 100 57 111 2 1439884480 236383792 12903424
08:49:35 7195 8192000000 10321349920 10745311232 1179381728 12.584 99 62 123 0 1424797424 230884912 14530784
08:50:48 4472 8192000000 10391588256 10815750144 1176762304 12.560 100 74 145 2 1493553936 299935632 16856000
08:51:56 3424 8192000000 10428133888 10834034688 1176503824 12.558 100 87 172 0 1527501648 330810992 20186832
08:53:08 4921 8192000000 10385815520 10808303616 1170351360 12.500 100 78 155 3 1487166832 298891664 17923808
08:54:18 2368 8192000000 10429820432 10841452544 1169253392 12.490 100 70 138 0 1526291904 340844656 16193856
08:55:24 1027 8192000000 10439923312 10843779072 1151838112 12.327 99 87 173 0 1531035360 358232528 20964720
08:56:32 2791 8192000000 10480985440 10906603520 1157136128 12.376 100 69 136 2 1576348528 402892192 16320208
08:57:38 6558 8192000000 10426461264 10852675584 1146001120 12.272 99 47 94 1 1518352592 359920944 12430528
08:58:46 13243 8192000000 10388155232 10827333632 1142446672 12.239 100 36 72 0 1497483648 345445440 9591536
09:00:02 9377 8192000000 10406063568 10861404160 1138968624 12.206 100 35 70 0 1499401872 351266608 9166640
09:01:28 28526 8192000000 10278832352 10745253888 1095497824 11.795 99 24 47 0 1449566656 347523424 6545408
09:03:12 99603 8192000000 10028902800 10482032640 1029525440 11.164 93 6 12 0 1059593552 28596048 1472064
09:05:54 20472 8192000000 10081352256 10507776000 1107512944 11.909 100 42 85 1 1171939088 55798128 8628016
09:07:19 72860 8192000000 10069491040 10483425280 1052071568 11.381 94 16 30 0 1089505760 34721536 2712656
09:09:32 97512 8192000000 10104089168 10545696768 1103008352 11.866 98 31 62 2 1186754736 76742672 7003712
09:12:13 26228 8192000000 10065375584 10498973696 1125606384 12.080 100 25 50 0 1164715632 33670464 5438784
09:13:45 87053 8192000000 10127876912 10589261824 1136944064 12.187 100 33 66 0 1217725616 73936272 6845280
09:16:13 51171 8192000000 10123343952 10574589952 1142256768 12.237 100 18 36 0 1206285248 60250944 3777536
09:18:34 129594 8192000000 10082986176 10529992704 1132080896 12.141 99 21 42 1 1222268112 85515664 4671552
09:21:45 21140 8192000000 10068503520 10509852672 1134131376 12.160 99 31 59 1 1165660496 25659712 5869408
09:23:08 68161 8192000000 10108789280 10581659648 1151691808 12.325 100 16 31 0 1216806848 61819584 3295456
09:25:19 66699 8192000000 10078734880 10570489856 1148704096 12.297 100 15 30 0 1202441952 50619312 3118544
09:27:41 29255 8192000000 10074885840 10546458624 1135584352 12.174 99 15 30 0 1179176672 40640960 2951360
09:29:53 21535 8192000000 10082727072 10519363584 1132870912 12.148 99 30 60 0 1174539168 35524496 6143760
09:31:15 39475 8192000000 10067384304 10527023104 1121564064 12.042 98 22 44 1 1161277296 35369808 4343424
09:32:56 28721 8192000000 10156367008 10594852864 1134724144 12.166 99 36 71 2 1242742240 99945616 8072480
09:34:26 67602 8192000000 10066579536 10586554368 1112096928 11.952 98 17 33 2 1171221408 55307488 3816992
09:36:34 33774 8192000000 10077733392 10519891968 1101809856 11.855 97 31 63 0 1153948432 46003472 6135104
09:38:12 55928 8192000000 10147133840 10590388224 1131939312 12.140 99 39 77 0 1205658832 66388528 7330992
09:40:56 57828 8192000000 10061248160 10535366656 1123431552 12.059 99 17 34 0 1179814496 53412720 2970224
09:42:55 72481 8192000000 10080912752 10550358016 1133008512 12.150 99 10 20 1 1179000880 43912192 2080176
09:45:09 48064 8192000000 10082451184 10520834048 1097995792 11.819 97 33 65 2 1182799472 76811824 7991856
09:46:58 42305 8192000000 10138599856 10588987392 1113639776 11.967 98 26 52 0 1201543200 82365408 5538016
09:49:00 121004 8192000000 10024721168 10450866176 1002194400 10.900 89 18 36 1 1048478480 42834896 3449184
09:52:02 40467 8192000000 10115125440 10557804544 1090305648 11.746 98 49 98 1 1204258048 104128256 9824144
09:53:45 77987 8192000000 10126750400 10650394624 1108651664 11.920 99 14 28 1 1161123328 49378784 3092880
09:56:05 137662 8192000000 10050648208 10509676544 1089271488 11.736 97 20 40 0 1146599088 53197424 4130176
10:01:15 44938 8192000000 10047223056 10502873088 1096543440 11.805 98 12 24 0 1132267104 33167184 2556480
10:04:11 63287 8192000000 10054442608 10488160256 1103322928 11.869 98 18 36 0 1152702016 45597296 3781792
10:06:15 49280 8192000000 10101904592 10572742656 1128861136 12.111 100 21 43 1 1214516704 80947488 4708080
10:08:15 39493 8192000000 10084371408 10526814208 1138253184 12.199 100 28 56 0 1175736512 31667664 5815664
10:09:58 57708 8192000000 10111688416 10624966656 1151731552 12.326 100 19 38 1 1235752736 80018112 4003072
10:12:14 41237 8192000000 10084933776 10532380672 1148159600 12.292 100 32 64 1 1172119920 16831040 7129280
10:13:57 74390 8192000000 10125161792 10584395776 1106288336 11.897 96 22 44 0 1163918336 53248048 4381952
10:16:13 48306 8192000000 10073944864 10596274176 1139735680 12.213 100 7 14 0 1181936720 40794976 1406064
10:18:02 39925 8192000000 10068300848 10528079872 1137709120 12.194 99 22 44 1 1167875840 25509616 4657104
10:19:44 50545 8192000000 10061198608 10530934784 1100274192 11.840 97 10 20 1 1159172944 56990272 1908480
10:21:35 91391 8192000000 10083815344 10553901056 1112688192 11.958 97 13 26 0 1158623264 43092832 2842240
10:24:19 66212 8192000000 10126667280 10575663104 1122828496 12.054 97 16 33 0 1183905360 57568176 3508688
10:26:31 74627 8192000000 10174952576 10636390400 1132607392 12.146 98 18 36 0 1236248144 99535280 4105472
10:28:46 66752 8192000000 10235886320 10728529920 1125274288 12.077 97 25 50 1 1270532608 138913408 6344912
10:30:53 82025 8192000000 10110542816 10634936320 1126541776 12.089 98 19 39 0 1188155696 57790912 3823008
10:34:30 49008 8192000000 10073199056 10500161536 1089519152 11.738 96 39 75 2 1143949808 46601808 7828848
10:36:29 52951 8192000000 9242087936 9545011200 382322240 4.458 35 9 16 0 416351024 32212560 1816224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-09-2021 10:39:08 to 29-09-2021 10:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 75 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-09-2021 10:39:24 to 29-09-2021 10:39:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          234269
MON$OLDEST_ACTIVE               234270
MON$OLDEST_SNAPSHOT             234270
MON$NEXT_TRANSACTION            234270
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2059872
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:259: 
:260: mscs-test-161.budzdorov.ru    Wed Sep 29 10:37:37 2021
:261:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:262: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

29.09.21 10:39:27. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-09-29 10:39:29 No severe PSQL-related problems occured
29.09.21 10:39:31. End of report.