Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2023 04:39
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 14937056 kB
MemAvailable 30388992 kB
Buffers 20 kB
Cached 16732032 kB
SwapCached 5556 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.3
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 2023-01-03 04:37:21
DTS_END                         2023-01-03 04:37:21.6340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51960
TRN_ID                          227935
ATT_ID                          625
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: 9144.08 21699 1097289
customer order: creation 179.18 444 21501
customer order: refuse 15.48 387 1857
order to supplier: creation 47.48 1270 5698
order to supplier: removal 3.33 149467 400
invoice (draft): creation 47.01 3214 5641
invoice (draft): removal 49.93 905 5992
invoice accept: apply 43.21 24826 5185
invoice accept: cancel 45.06 1191 5407
customer reserve: creation 4020.12 236 482414
customer reserve: removal 4389.80 90 526776
realization accept: apply 86.80 280 10416
realization accept: cancel 81.87 155 9824
payment from customer: creation 62.08 174 7449
payment from customer: removal 5.83 111 699
payment to supplier: creation 50.91 193 6109
payment to supplier: removal 3.07 119 368
service: total inventory turnovers 4.43 3531 531
service: total monetary turnovers 7.70 363 924
service: aggregate perf. data 0.78 19463 94
service: refresh index statistics 0.03 227554 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2023 04:39:50 to 03-01-2023 04:39:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5716
TEST_TIME 2 5325
TEST_TIME 3 5636
TEST_TIME 4 5917
TEST_TIME 5 6124
TEST_TIME 6 6250
TEST_TIME 7 6007
TEST_TIME 8 6055
TEST_TIME 9 6238
TEST_TIME 10 6303
TEST_TIME 11 6536
TEST_TIME 12 6630
TEST_TIME 13 7082
TEST_TIME 14 7195
TEST_TIME 15 7307
TEST_TIME 16 7500
TEST_TIME 17 7646
TEST_TIME 18 7656
TEST_TIME 19 7719
TEST_TIME 20 8163
TEST_TIME 21 8294
TEST_TIME 22 8421
TEST_TIME 23 9059
TEST_TIME 24 9002
TEST_TIME 25 9184
TEST_TIME 26 9579
TEST_TIME 27 9741
TEST_TIME 28 10004
TEST_TIME 29 10439
TEST_TIME 30 11015
TEST_TIME 31 11008
TEST_TIME 32 11067
TEST_TIME 33 11084
TEST_TIME 34 11032
TEST_TIME 35 10956
TEST_TIME 36 11010
TEST_TIME 37 10854
TEST_TIME 38 10801
TEST_TIME 39 10763
TEST_TIME 40 10707
TEST_TIME 41 10526
TEST_TIME 42 10330
TEST_TIME 43 10246
TEST_TIME 44 10298
TEST_TIME 45 10157
TEST_TIME 46 10235
TEST_TIME 47 10229
TEST_TIME 48 10129
TEST_TIME 49 10158
TEST_TIME 50 10091
TEST_TIME 51 10026
TEST_TIME 52 9948
TEST_TIME 53 9915
TEST_TIME 54 9869
TEST_TIME 55 9850
TEST_TIME 56 9780
TEST_TIME 57 9690
TEST_TIME 58 9614
TEST_TIME 59 9596
TEST_TIME 60 9626
TEST_TIME 61 9569
TEST_TIME 62 9714
TEST_TIME 63 9721
TEST_TIME 64 9762
TEST_TIME 65 9732
TEST_TIME 66 9782
TEST_TIME 67 9727
TEST_TIME 68 9746
TEST_TIME 69 9767
TEST_TIME 70 9932
TEST_TIME 71 10023
TEST_TIME 72 10026
TEST_TIME 73 9962
TEST_TIME 74 9893
TEST_TIME 75 9777
TEST_TIME 76 9735
TEST_TIME 77 9704
TEST_TIME 78 9663
TEST_TIME 79 9595
TEST_TIME 80 9557
TEST_TIME 81 9480
TEST_TIME 82 9487
TEST_TIME 83 9517
TEST_TIME 84 9492
TEST_TIME 85 9476
TEST_TIME 86 9463
TEST_TIME 87 9473
TEST_TIME 88 9488
TEST_TIME 89 9520
TEST_TIME 90 9536
TEST_TIME 91 9551
TEST_TIME 92 9599
TEST_TIME 93 9593
TEST_TIME 94 9564
TEST_TIME 95 9599
TEST_TIME 96 9559
TEST_TIME 97 9484
TEST_TIME 98 9424
TEST_TIME 99 9423
TEST_TIME 100 9379
TEST_TIME 101 9332
TEST_TIME 102 9332
TEST_TIME 103 9297
TEST_TIME 104 9280
TEST_TIME 105 9268
TEST_TIME 106 9233
TEST_TIME 107 9236
TEST_TIME 108 9210
TEST_TIME 109 9211
TEST_TIME 110 9257
TEST_TIME 111 9318
TEST_TIME 112 9437
TEST_TIME 113 9474
TEST_TIME 114 9425
TEST_TIME 115 9367
TEST_TIME 116 9350
TEST_TIME 117 9320
TEST_TIME 118 9283
TEST_TIME 119 9242
TEST_TIME 120 9171

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2023 04:39:54 to 03-01-2023 04:39:57.

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 1541648 1541648 0 0.00 0 16491 13 0 0
doc_list_biud 1541643 1541643 0 0.00 0 42622 36 0 0
sp_add_invoice_to_stock 5328 5185 143 2.68 49 277155 24826 0 143
sp_cancel_adding_invoice 5407 5407 0 0.00 6 80968 1191 0 0
sp_cancel_client_order 1857 1857 0 0.00 6 15202 387 0 0
sp_cancel_customer_reserve 526776 526776 0 0.00 1 34975 91 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 2842 112 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 4 4085 120 0 0
sp_cancel_supplier_invoice 6005 5992 13 0.21 3 42674 905 0 13
sp_cancel_supplier_order 400 400 0 0.00 12 1422993 149467 0 0
sp_cancel_write_off 9824 9824 0 0.00 2 6977 155 0 0
sp_client_order 21501 21501 0 0.00 5 32481 445 0 0
sp_customer_reserve 482414 482414 0 0.00 0 120548 236 0 0
sp_fill_shopping_cart 32831 32831 0 0.00 1 22243 81 0 0
sp_fill_shopping_cart_clo_res 482390 482390 0 0.00 0 16654 18 0 0
sp_get_clo_for_invoice 5137 5137 0 0.00 0 28559 242 0 0
sp_kill_cost_storno 16298 16298 0 0.00 0 6913 60 0 0
sp_kill_qstorno_ret_qs2qd 1069768 1069768 0 0.00 0 42619 40 0 0
sp_kill_qty_storno 565809 565809 0 0.00 0 42621 91 0 0
sp_lock_selected_doc 21527 21527 0 0.00 0 12066 10 0 0
sp_make_cost_storno 29111 29111 0 0.00 0 16325 76 0 0
sp_multiply_rows_for_pdistr 29111 29111 0 0.00 0 9830 38 0 0
sp_multiply_rows_for_qdistr 21501 21501 0 0.00 0 11410 176 0 0
sp_pay_from_customer 7449 7449 0 0.00 5 26876 174 0 0
sp_pay_to_supplier 6109 6109 0 0.00 6 25039 193 0 0
sp_payment_common 13558 13558 0 0.00 2 9617 47 0 0
sp_qd_handle_on_cancel_clo 1857 1857 0 0.00 1 15182 303 0 0
sp_qd_handle_on_invoice_upd_sts 10544 10544 0 0.00 0 10724 467 0 0
sp_qd_handle_on_reserve_upd_sts 10416 10416 0 0.00 0 24505 58 0 0
sp_reserve_write_off 10416 10416 0 0.00 8 26024 280 0 0
sp_supplier_invoice 5641 5641 0 0.00 9 80850 3214 0 0
sp_supplier_order 5698 5698 0 0.00 9 90738 1271 0 0
srv_aggregate_perf_data 94 94 0 0.00 2321 185201 19464 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 783 125501 33891 0 0
srv_find_qd_qs_mism 1046426 1046426 0 0.00 0 12451 10 0 0
srv_make_invnt_saldo 531 531 0 0.00 40 57129 3532 0 0
srv_make_money_saldo 924 924 0 0.00 1 14265 363 0 0
srv_recalc_idx_stat 4 4 0 0.00 90910 280908 227554 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5188 5188 0 0.00 1 13408 883 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 7067 458 0 0
t$perf-norm:sp_cancel_client_order 1857 1857 0 0.00 0 4713 31 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 1111 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 3505 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 1441 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 690 26 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 99774 6411 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 3482 24 0 0
t$perf-norm:sp_client_order 21501 21501 0 0.00 0 8966 36 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 2284 20 0 0
t$perf-norm:sp_pay_from_customer 7449 7449 0 0.00 0 5802 22 0 0
t$perf-norm:sp_pay_to_supplier 6109 6109 0 0.00 0 2805 20 0 0
t$perf-norm:sp_reserve_write_off 10416 10416 0 0.00 0 9955 25 0 0
t$perf-norm:sp_supplier_invoice 5641 5641 0 0.00 0 4747 27 0 0
t$perf-norm:sp_supplier_order 5698 5698 0 0.00 0 7216 32 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 268 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 296 8 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 583 10 0 0
t$perf-norm:srv_make_money_saldo 924 924 0 0.00 0 243 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 99 43 0 0
v_add_invoice_to_stock 10560 10274 286 2.70 0 9893 28 0 286
v_all_customers 39520 39520 0 0.00 0 64 4 0 0
v_all_suppliers 31622 31622 0 0.00 0 82 4 0 0
v_all_wares 43002 43002 0 0.00 0 136 4 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 388 14 0 0
v_cancel_client_order 3714 3714 0 0.00 0 1373 8 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 2486 13 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 387 10 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 4070 15 0 0
v_cancel_supplier_invoice 892 866 26 2.91 0 2823 28 0 26
v_cancel_supplier_order 800 800 0 0.00 0 1567 11 0 0
v_cancel_write_off 2138 2138 0 0.00 0 5245 26 0 0
v_max_id_clo_ord 5697 5697 0 0.00 0 4308 6 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 5074 24 0 0
v_max_id_ord_sup 5632 5632 0 0.00 0 5361 10 0 0
v_max_non_paid_invoice 1627 1627 0 0.00 0 198 7 0 0
v_max_non_paid_realizn 3778 3778 0 0.00 0 5430 11 0 0
v_min_id_clo_ord 5697 5697 0 0.00 0 1631 8 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 16343 26 0 0
v_min_id_ord_sup 5632 5632 0 0.00 0 5995 9 0 0
v_min_non_paid_invoice 6109 6109 0 0.00 0 6466 16 0 0
v_min_non_paid_realizn 7449 7449 0 0.00 0 7068 21 0 0
v_our_firm 10824 10824 0 0.00 0 57 5 0 0
v_reserve_write_off 20830 20830 0 0.00 0 25482 13 0 0
x_lock_depdocs_on_canc_invoice 10966 10966 0 0.00 0 12365 116 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 13122 399 0 0
x_make_qstorno_1000_1200 5698 5698 0 0.00 3 90594 1111 0 0
x_make_qstorno_1200_2000 5641 5641 0 0.00 4 80836 2992 0 0
x_make_qstorno_2100_3300 482410 482410 0 0.00 0 120545 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2023 04:39:58 to 03-01-2023 04:40:02.

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
02:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:26 17552 8192000000 9705631056 10000732160 787336112 8.768 100 90 180 0 817648864 8251504 22061248
02:09:48 13197 8192000000 9749314736 10055974912 829476720 9.194 100 86 171 0 861653520 11710256 20466544
02:11:07 16877 8192000000 9804764560 10119241728 867449808 9.575 100 84 168 0 904196320 17412864 19333648
02:12:29 8307 8192000000 9854071152 10184527872 905088272 9.949 100 78 151 0 961820592 40348880 16383440
02:13:43 4927 8192000000 9889862176 10229350400 948170464 10.373 100 80 150 1 1000621504 37242224 15208816
02:14:51 2660 8192000000 9957722176 10310991872 999079040 10.870 100 88 177 0 1082664880 65518416 18067424
02:15:57 1427 8192000000 10009240432 10372030464 1026292320 11.133 100 94 178 1 1130666128 86775136 17598672
02:17:00 840 8192000000 10031305424 10397282304 1047052544 11.332 100 98 184 2 1146626128 80273424 19300160
02:18:03 975 8192000000 10039844032 10415063040 1057448064 11.432 100 90 173 1 1158582032 83977760 17156208
02:19:05 1034 8192000000 10028818560 10405490688 1064325200 11.498 100 93 183 1 1147842576 65760848 17756528
02:20:08 716 8192000000 10056768960 10440974336 1071352912 11.565 100 90 172 0 1178190160 88647728 18189520
02:21:10 1104 8192000000 10054568816 10437242880 1074312480 11.593 100 96 187 0 1175675632 82445520 18917632
02:22:12 1125 8192000000 10056827360 10443788288 1079007488 11.638 100 96 184 0 1177666912 79707184 18952240
02:23:15 1192 8192000000 10056593680 10441961472 1084790544 11.693 100 81 154 1 1178626640 78273456 15562640
02:24:18 1370 8192000000 10058824672 10445119488 1087296608 11.717 100 94 179 2 1177753440 71866064 18590768
02:25:21 1249 8192000000 10053642512 10439921664 1091681040 11.759 100 99 194 2 1182303968 70082768 20540160
02:26:23 1162 8192000000 10057608128 10440568832 1097244800 11.811 100 96 189 0 1184107568 67516656 19346112
02:27:26 1280 8192000000 10056983888 10452631552 1098804176 11.826 100 83 154 2 1180193616 65795712 15593728
02:28:29 1259 8192000000 10083289024 10475872256 1097156288 11.811 100 97 189 2 1207922992 91167696 19599008
02:29:31 1264 8192000000 10068683568 10456322048 1097078048 11.810 100 98 191 2 1191377952 74479488 19820416
02:30:34 1272 8192000000 10036817104 10421981184 1077905808 11.628 100 89 167 2 1160777776 65225136 17646832
02:31:35 1437 8192000000 10016404752 10396221440 1060657344 11.463 100 85 162 0 1140461216 63467232 16336640
02:32:38 1043 8192000000 9986482192 10360356864 1042251456 11.286 100 96 180 2 1108267552 48948576 17067520
02:33:40 1763 8192000000 10014233952 10392989696 1044547344 11.308 100 74 139 2 1138888832 80203168 14138320
02:34:43 971 8192000000 9979136272 10353156096 1037573824 11.241 100 78 142 3 1100078736 48120944 14383968
02:35:45 2932 8192000000 9991153104 10371067904 1050236544 11.363 99 69 130 0 1112909664 49237552 13435568
02:36:49 927 8192000000 9992057792 10373812224 1042693248 11.291 100 82 157 1 1116917200 57631568 16592384
02:37:56 1250 8192000000 10003226096 10375335936 1047115344 11.333 100 92 167 1 1122717776 59464896 16137536
02:39:06 2552 8192000000 10005782256 10382491648 1053811056 11.397 98 81 159 1 1130277696 61171040 15295600
02:40:13 1144 8192000000 10064618704 10445123584 1082106672 11.668 100 89 175 0 1187253088 86120016 19026400
02:41:18 3479 8192000000 10069579184 10461908992 1101887424 11.856 100 80 137 1 1189382864 72659488 14835952
02:42:24 895 8192000000 10119323568 10506661888 1113546304 11.966 100 91 178 1 1239126800 107323344 18257152
02:43:30 1952 8192000000 10158506800 10558742528 1125577840 12.080 100 71 136 1 1277743712 137770640 14395232
02:44:36 1365 8192000000 10194234000 10588364800 1135551328 12.174 100 93 173 5 1307692560 154610672 17530560
02:45:41 909 8192000000 10212797424 10607071232 1147837712 12.289 100 87 170 2 1323997104 158046448 18112944
02:46:49 3960 8192000000 10245142896 10651492352 1157769552 12.382 100 75 149 1 1361688080 187619856 16298672
02:48:01 1197 8192000000 10276121568 10681425920 1167370512 12.472 100 92 183 0 1393254384 204849616 21034256
02:49:05 1106 8192000000 10301843792 10701836288 1172028816 12.516 100 98 191 1 1412436288 218494304 21913168
02:50:08 1308 8192000000 10335985792 10760994816 1179083040 12.582 100 97 186 3 1450370928 249509088 21778800
02:51:13 1227 8192000000 10375252016 10784968704 1185570976 12.642 100 97 192 0 1489375952 281486976 22318000
02:52:17 923 8192000000 10422644912 10826924032 1191058784 12.693 100 98 196 1 1528296960 314332736 22905440
02:53:20 1363 8192000000 10429900176 10859917312 1194731968 12.727 100 94 177 1 1538416576 323261536 20423072
02:54:24 1122 8192000000 10458973728 10871537664 1197062896 12.749 100 92 174 2 1570110656 351083408 21964352
02:55:28 1209 8192000000 10474149840 10886488064 1180512496 12.595 100 96 177 1 1580356400 378690656 21153248
02:56:33 2491 8192000000 10503179104 10951757824 1189455104 12.678 100 93 185 3 1608775808 396862832 22457872
02:57:38 2036 8192000000 10471580656 10935902208 1189439296 12.678 100 74 145 1 1596553984 389615600 17499088
02:58:46 774 8192000000 10435974048 10888323072 1173820016 12.533 100 93 177 0 1542761440 347662240 21279184
02:59:48 1290 8192000000 10427321104 10917285888 1180117184 12.591 100 73 145 0 1534658672 336057648 18483840
03:00:53 4161 8192000000 10444110528 10881286144 1177130656 12.563 100 64 121 2 1547811312 355334272 15346384
03:02:01 10911 8192000000 10418954016 10899308544 1174882352 12.542 100 39 78 0 1525739552 340316464 10540736
03:03:21 28402 8192000000 10365973408 10852642816 1157040032 12.376 99 31 62 0 1497719744 332484288 8195424
03:05:07 27182 8192000000 10285052912 10756747264 1117913408 12.007 98 18 36 0 1375540560 252911040 4716112
03:06:36 26776 8192000000 10146577472 10669113344 1122877888 12.054 99 23 45 0 1277007408 148890656 5238864
03:08:08 47609 8192000000 10085899328 10571976704 1100486560 11.842 97 13 23 0 1169491680 66543792 2461328
03:09:56 79827 8192000000 10041498608 10499530752 1001493408 10.893 89 22 43 3 1041949200 35854384 4601408
03:12:17 125499 8192000000 10052296640 10500931584 1113015056 11.961 99 21 40 0 1179947184 62542032 4390096
03:15:37 81589 8192000000 10085358288 10545586176 1090459904 11.747 96 20 35 0 1125060336 31114496 3485936
03:18:17 107714 8192000000 10159280208 10596327424 1122177584 12.048 99 41 72 2 1194194064 64303536 7712944
03:21:52 42563 8192000000 10082348880 10516738048 1124010016 12.065 100 39 76 1 1190836848 58427424 8399408
03:23:41 12315 8192000000 10095929536 10560905216 1111719152 11.949 99 45 90 0 1192436272 71300832 9416288
03:24:57 24171 8192000000 10069131040 10501750784 1120700784 12.034 100 41 76 2 1156503584 27824288 7978512
03:26:22 26194 8192000000 10104378496 10545823744 1131810976 12.138 100 39 75 0 1205888896 66032144 8045776
03:27:53 69145 8192000000 10115670592 10575212544 1126448208 12.088 99 32 62 0 1224742528 91381792 6912528
03:30:12 23759 8192000000 10132771376 10583035904 1127879328 12.101 99 32 62 0 1230169568 95385248 6904992
03:31:36 61378 8192000000 10050855152 10489335808 1130288128 12.124 99 31 58 0 1167598944 31537488 5773328
03:33:38 20993 8192000000 10107398304 10565521408 1144339792 12.256 100 38 73 0 1196333712 45112368 6881552
03:35:42 67589 8192000000 10104778864 10599198720 1122962640 12.055 98 15 30 0 1197871104 71803040 3105424
03:37:50 41558 8192000000 10143904832 10586804224 1108982608 11.923 97 21 40 0 1179675696 66314048 4379040
03:39:32 35004 8192000000 10076759856 10521157632 1112207504 11.953 99 22 43 0 1152498944 35875280 4416160
03:41:08 33200 8192000000 10100953744 10565169152 1126432304 12.088 100 49 89 1 1219179936 84148400 8599232
03:42:41 20898 8192000000 10156056496 10588266496 1119179056 12.019 98 36 72 0 1219139472 92701168 7259248
03:44:03 22548 8192000000 10169243344 10601275392 1136943248 12.187 99 39 76 0 1251255328 107068320 7243760
03:45:32 49497 8192000000 10238418480 10678665216 1144487312 12.258 99 39 78 1 1308266176 155954896 7823968
03:47:31 52920 8192000000 10100838480 10585767936 1103251520 11.868 97 19 38 0 1215879840 108334928 4293392
03:50:01 27106 8192000000 10085182272 10525655040 1097991392 11.819 97 37 73 0 1147208896 41965968 7251536
03:53:26 44962 8192000000 10063443568 10514493440 1109097184 11.924 99 40 80 0 1167055152 49694976 8262992
03:55:17 90032 8192000000 10061734416 10515152896 1080085872 11.648 96 11 22 0 1126424144 43979616 2358656
03:57:47 101580 8192000000 10090170560 10591158272 1125784624 12.082 100 15 31 0 1195739840 66858368 3096848
04:00:31 49715 8192000000 10052814656 10499670016 1076934000 11.618 96 20 39 0 1114237088 33270080 4033008
04:02:40 21230 8192000000 10104662672 10531549184 1128459008 12.107 100 49 94 0 1200414832 61713552 10242272
04:04:04 52261 8192000000 10128351360 10580684800 1113953680 11.970 99 32 64 0 1226113264 105471840 6687744
04:06:08 84093 8192000000 10109333072 10592030720 1103105040 11.867 98 24 44 0 1198014896 90490752 4419104
04:08:40 29707 8192000000 10115492000 10564100096 1121991040 12.046 100 36 72 1 1192919008 64070720 6857248
04:10:10 73224 8192000000 10087103200 10533249024 1102076016 11.857 98 19 38 0 1145113232 39232560 3804656
04:12:23 81368 8192000000 10080327232 10543398912 1116404832 11.993 99 24 48 0 1178699056 57676928 4617296
04:17:26 71737 8192000000 10071600016 10518429696 1094337696 11.784 97 14 29 0 1135451648 38738320 2375632
04:19:38 52254 8192000000 10123990624 10567606272 1126567904 12.089 99 28 51 1 1200401456 68338112 5495440
04:21:34 47388 8192000000 10162229280 10614849536 1152310816 12.331 100 36 72 0 1255051824 94534592 8206416
04:23:34 21507 8192000000 10199471072 10635964416 1141201232 12.227 99 46 92 0 1277408096 125216992 10989872
04:25:00 20439 8192000000 10218010576 10652495872 1137793104 12.195 99 35 70 0 1280991488 134939312 8259072
04:26:23 12251 8192000000 10228879920 10666831872 1151131456 12.320 100 40 80 0 1320575040 159826880 9616704
04:27:37 47149 8192000000 10224732928 10681438208 1109544576 11.928 96 21 42 0 1270070640 155683856 4842208
04:29:25 66135 8192000000 10115110096 10597339136 1125682000 12.081 99 15 30 0 1220849568 92163024 3004544
04:31:31 62879 8192000000 10084991088 10519941120 1133319696 12.153 100 41 76 1 1178267824 36263264 8684864
04:33:36 79382 8192000000 10067721888 10549776384 1117478368 12.003 99 17 33 1 1165552736 44519264 3555104
04:37:13 24802 8192000000 9333557600 9662595072 467974192 5.403 42 4 8 0 494000736 25364992 661552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-01-2023 04:40:04 to 03-01-2023 04:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2023 04:40:21 to 03-01-2023 04:40:23.

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          228847
MON$OLDEST_ACTIVE               228848
MON$OLDEST_SNAPSHOT             228848
MON$NEXT_TRANSACTION            228848
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                       2072672
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:857: 
:858: mscs-test-161.budzdorov.ru    Tue Jan  3 04:37:53 2023
:859:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:860: 
:861: 
:862: mscs-test-161.budzdorov.ru    Tue Jan  3 04:37:55 2023
:863:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:864: 
--- end of diff output ---

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

03.01.23 04:40:24. 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
2023-01-03 04:40:27 No severe PSQL-related problems occured
03.01.23 04:40:28. End of report.