Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2022 19: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 16343876 kB
MemAvailable 31300496 kB
Buffers 0 kB
Cached 15355912 kB
SwapCached 2324 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 3.0.9
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2022-01-12 19:38:02
DTS_END                         2022-01-12 19:38:02.5830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49678
TRN_ID                          248503
ATT_ID                          704
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: 9369.31 20555 1124317
customer order: creation 188.12 349 22574
customer order: refuse 15.74 355 1889
order to supplier: creation 49.13 1270 5896
order to supplier: removal 3.38 91623 405
invoice (draft): creation 49.22 3273 5906
invoice (draft): removal 50.97 742 6116
invoice accept: apply 44.62 16543 5354
invoice accept: cancel 45.98 839 5518
customer reserve: creation 4058.64 166 487037
customer reserve: removal 4544.54 54 545345
realization accept: apply 91.58 205 10990
realization accept: cancel 89.15 108 10698
payment from customer: creation 63.46 111 7615
payment from customer: removal 6.03 72 723
payment to supplier: creation 52.38 118 6285
payment to supplier: removal 3.59 63 431
service: total inventory turnovers 5.01 1286 601
service: total monetary turnovers 6.78 73 814
service: aggregate perf. data 0.97 17603 116
service: refresh index statistics 0.03 276244 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2022 19:39:31 to 12-01-2022 19:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7079
TEST_TIME 2 5456
TEST_TIME 3 5768
TEST_TIME 4 6276
TEST_TIME 5 5716
TEST_TIME 6 6056
TEST_TIME 7 6031
TEST_TIME 8 6511
TEST_TIME 9 6586
TEST_TIME 10 6497
TEST_TIME 11 6548
TEST_TIME 12 6570
TEST_TIME 13 6577
TEST_TIME 14 6803
TEST_TIME 15 6828
TEST_TIME 16 7419
TEST_TIME 17 7796
TEST_TIME 18 8133
TEST_TIME 19 8168
TEST_TIME 20 8974
TEST_TIME 21 8945
TEST_TIME 22 8922
TEST_TIME 23 9017
TEST_TIME 24 9519
TEST_TIME 25 9584
TEST_TIME 26 10287
TEST_TIME 27 10186
TEST_TIME 28 10046
TEST_TIME 29 9886
TEST_TIME 30 9859
TEST_TIME 31 9967
TEST_TIME 32 10088
TEST_TIME 33 10062
TEST_TIME 34 10043
TEST_TIME 35 9959
TEST_TIME 36 9991
TEST_TIME 37 10094
TEST_TIME 38 10035
TEST_TIME 39 9921
TEST_TIME 40 9772
TEST_TIME 41 9695
TEST_TIME 42 9662
TEST_TIME 43 9566
TEST_TIME 44 9591
TEST_TIME 45 9609
TEST_TIME 46 9687
TEST_TIME 47 9684
TEST_TIME 48 9638
TEST_TIME 49 9576
TEST_TIME 50 9545
TEST_TIME 51 9488
TEST_TIME 52 9591
TEST_TIME 53 9565
TEST_TIME 54 9654
TEST_TIME 55 9663
TEST_TIME 56 9691
TEST_TIME 57 9637
TEST_TIME 58 9560
TEST_TIME 59 9717
TEST_TIME 60 9893
TEST_TIME 61 9965
TEST_TIME 62 10041
TEST_TIME 63 10052
TEST_TIME 64 9996
TEST_TIME 65 9909
TEST_TIME 66 9815
TEST_TIME 67 9823
TEST_TIME 68 9796
TEST_TIME 69 9725
TEST_TIME 70 9768
TEST_TIME 71 9760
TEST_TIME 72 9695
TEST_TIME 73 9664
TEST_TIME 74 9670
TEST_TIME 75 9579
TEST_TIME 76 9515
TEST_TIME 77 9541
TEST_TIME 78 9550
TEST_TIME 79 9654
TEST_TIME 80 9609
TEST_TIME 81 9665
TEST_TIME 82 9676
TEST_TIME 83 9701
TEST_TIME 84 9745
TEST_TIME 85 9730
TEST_TIME 86 9723
TEST_TIME 87 9798
TEST_TIME 88 9818
TEST_TIME 89 9818
TEST_TIME 90 9737
TEST_TIME 91 9708
TEST_TIME 92 9628
TEST_TIME 93 9610
TEST_TIME 94 9577
TEST_TIME 95 9545
TEST_TIME 96 9560
TEST_TIME 97 9593
TEST_TIME 98 9576
TEST_TIME 99 9573
TEST_TIME 100 9606
TEST_TIME 101 9564
TEST_TIME 102 9539
TEST_TIME 103 9573
TEST_TIME 104 9655
TEST_TIME 105 9653
TEST_TIME 106 9658
TEST_TIME 107 9652
TEST_TIME 108 9671
TEST_TIME 109 9692
TEST_TIME 110 9661
TEST_TIME 111 9592
TEST_TIME 112 9565
TEST_TIME 113 9534
TEST_TIME 114 9542
TEST_TIME 115 9488
TEST_TIME 116 9492
TEST_TIME 117 9476
TEST_TIME 118 9418
TEST_TIME 119 9415
TEST_TIME 120 9372

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2022 19:39:35 to 12-01-2022 19:39:38.

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 1575981 1575981 0 0.00 0 25453 7 0 0
doc_list_biud 1575981 1575981 0 0.00 0 28850 24 0 0
sp_add_invoice_to_stock 5514 5354 160 2.90 26 169734 16544 0 160
sp_cancel_adding_invoice 5518 5518 0 0.00 4 94139 840 0 0
sp_cancel_client_order 1889 1889 0 0.00 4 9422 356 0 0
sp_cancel_customer_reserve 545345 545345 0 0.00 1 28852 54 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 3 6473 72 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 2749 63 0 0
sp_cancel_supplier_invoice 6122 6116 6 0.09 2 26139 742 0 6
sp_cancel_supplier_order 405 405 0 0.00 9 860416 91623 0 0
sp_cancel_write_off 10698 10698 0 0.00 1 15525 108 0 0
sp_client_order 22574 22574 0 0.00 3 33114 349 0 0
sp_customer_reserve 487037 487037 0 0.00 0 99754 167 0 0
sp_fill_shopping_cart 34357 34357 0 0.00 1 9272 35 0 0
sp_fill_shopping_cart_clo_res 487031 487031 0 0.00 0 22124 13 0 0
sp_get_clo_for_invoice 5348 5348 0 0.00 0 15273 251 0 0
sp_kill_cost_storno 17370 17370 0 0.00 0 8124 50 0 0
sp_kill_qstorno_ret_qs2qd 1107909 1107909 0 0.00 0 26132 30 0 0
sp_kill_qty_storno 586309 586309 0 0.00 0 28849 65 0 0
sp_lock_selected_doc 22531 22531 0 0.00 0 8844 8 0 0
sp_make_cost_storno 30238 30238 0 0.00 0 17074 54 0 0
sp_multiply_rows_for_pdistr 30238 30238 0 0.00 0 8474 31 0 0
sp_multiply_rows_for_qdistr 22574 22574 0 0.00 0 33105 151 0 0
sp_pay_from_customer 7615 7615 0 0.00 4 8676 111 0 0
sp_pay_to_supplier 6285 6285 0 0.00 4 9039 118 0 0
sp_payment_common 13900 13900 0 0.00 1 8145 28 0 0
sp_qd_handle_on_cancel_clo 1889 1889 0 0.00 0 9410 325 0 0
sp_qd_handle_on_invoice_upd_sts 10866 10866 0 0.00 0 20051 323 0 0
sp_qd_handle_on_reserve_upd_sts 10990 10990 0 0.00 0 21994 74 0 0
sp_reserve_write_off 10990 10990 0 0.00 6 22020 205 0 0
sp_supplier_invoice 5906 5906 0 0.00 6 69944 3273 0 0
sp_supplier_order 5896 5896 0 0.00 5 27150 1271 0 0
srv_aggregate_perf_data 117 116 1 0.85 3230 205176 17604 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1786 112230 44139 0 0
srv_find_qd_qs_mism 1073628 1073628 0 0.00 0 25452 6 0 0
srv_make_invnt_saldo 601 601 0 0.00 36 83573 1286 0 0
srv_make_money_saldo 814 814 0 0.00 0 20414 73 0 0
srv_recalc_idx_stat 4 4 0 0.00 140444 488927 276244 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5354 5354 0 0.00 0 10743 150 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 5215 138 0 0
t$perf-norm:sp_cancel_client_order 1889 1889 0 0.00 0 7503 22 0 0
t$perf-norm:sp_cancel_customer_reserve 772 772 0 0.00 0 719 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 433 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 1687 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 730 12 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 39765 1622 0 0
t$perf-norm:sp_cancel_write_off 1109 1109 0 0.00 0 8363 20 0 0
t$perf-norm:sp_client_order 22574 22574 0 0.00 0 22671 19 0 0
t$perf-norm:sp_customer_reserve 1090 1090 0 0.00 0 997 3 0 0
t$perf-norm:sp_pay_from_customer 7615 7615 0 0.00 0 6898 9 0 0
t$perf-norm:sp_pay_to_supplier 6285 6285 0 0.00 0 4950 9 0 0
t$perf-norm:sp_reserve_write_off 10990 10990 0 0.00 0 19946 12 0 0
t$perf-norm:sp_supplier_invoice 5906 5906 0 0.00 0 16796 18 0 0
t$perf-norm:sp_supplier_order 5896 5896 0 0.00 0 5680 17 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1138 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 364 7 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 2005 18 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 3780 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 11016 10696 320 2.90 0 4869 18 0 320
v_all_customers 40898 40898 0 0.00 0 108 0 0 0
v_all_suppliers 32388 32388 0 0.00 0 125 1 0 0
v_all_wares 45148 45148 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 252 5 0 0
v_cancel_client_order 3778 3778 0 0.00 0 1029 3 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 742 3 0 0
v_cancel_customer_reserve 1544 1544 0 0.00 0 808 4 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 606 4 0 0
v_cancel_supplier_invoice 852 840 12 1.40 0 3574 18 0 12
v_cancel_supplier_order 810 810 0 0.00 0 498 4 0 0
v_cancel_write_off 2218 2218 0 0.00 0 1425 13 0 0
v_max_id_clo_ord 5889 5889 0 0.00 0 1201 1 0 0
v_max_id_clo_res 1090 1090 0 0.00 0 1820 9 0 0
v_max_id_ord_sup 5894 5894 0 0.00 0 1882 2 0 0
v_max_non_paid_invoice 1874 1874 0 0.00 0 289 2 0 0
v_max_non_paid_realizn 4115 4115 0 0.00 0 3990 5 0 0
v_min_id_clo_ord 5889 5889 0 0.00 0 3794 7 0 0
v_min_id_clo_res 1090 1090 0 0.00 0 4888 12 0 0
v_min_id_ord_sup 5894 5894 0 0.00 0 6904 7 0 0
v_min_non_paid_invoice 6285 6285 0 0.00 0 8138 19 0 0
v_min_non_paid_realizn 7615 7615 0 0.00 0 7654 22 0 0
v_our_firm 11250 11250 0 0.00 0 19 1 0 0
v_reserve_write_off 21980 21980 0 0.00 0 7873 5 0 0
x_lock_depdocs_on_canc_invoice 11214 11214 0 0.00 0 23358 113 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 2501 259 0 0
x_make_qstorno_1000_1200 5896 5896 0 0.00 1 27139 1196 0 0
x_make_qstorno_1200_2000 5906 5906 0 0.00 3 69704 3147 0 0
x_make_qstorno_2100_3300 487037 487037 0 0.00 0 99754 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2022 19:39:39 to 12-01-2022 19:39:43.

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
17:08:05 9 8192000000 8803119760 8815808512 8237632 0.100 3 2 3 0 8382208 67616 76960
17:09:06 21550 8192000000 9898075744 10207207424 986839120 10.751 100 93 186 0 1017303328 5801392 24662816
17:10:40 24821 8192000000 9947037168 10266841088 1024101648 11.112 100 93 186 1 1057013040 9032752 23878640
17:12:08 12722 8192000000 9973247312 10304630784 1056804784 11.426 100 76 152 0 1085547312 9844304 18898224
17:13:28 15680 8192000000 10049541536 10396884992 1128312720 12.105 100 83 166 0 1158993808 10955280 19725808
17:14:46 9960 8192000000 10080230736 10438840320 1166984592 12.469 100 77 154 1 1198000048 12950560 18064896
17:15:58 4567 8192000000 10125220176 10493554688 1215738224 12.922 100 91 180 4 1249960224 13988672 20233328
17:17:05 3348 8192000000 10161044640 10531991552 1240983168 13.155 100 90 180 1 1275179024 14668880 19526976
17:18:10 2500 8192000000 10197419872 10576801792 1279101360 13.505 100 91 180 2 1312646528 12884560 20660608
17:19:15 1532 8192000000 10214664080 10600153088 1297846832 13.676 100 88 174 1 1331331616 13426976 20057808
17:20:18 18877 8192000000 10224121232 10611339264 1311345600 13.798 100 77 153 2 1339047648 10581088 17120960
17:21:39 6253 8192000000 10200173664 10611208192 1314151664 13.824 100 26 51 2 1326792704 5852384 6788656
17:22:46 4419 8192000000 10213873584 10626465792 1322165280 13.896 100 31 62 2 1335332800 6332704 6834816
17:23:52 8611 8192000000 10212128048 10630221824 1326224976 13.933 100 24 48 1 1338175280 6500240 5450064
17:25:01 15373 8192000000 10224140944 10641113088 1333012976 13.994 100 25 50 0 1345186544 6012656 6160912
17:26:17 36407 8192000000 10216934944 10641166336 1308372480 13.771 98 21 42 1 1319299376 6069376 4857520
17:27:58 7265 8192000000 10220870048 10642022400 1328817648 13.956 100 19 38 0 1339261568 5823728 4620192
17:29:07 9001 8192000000 10199268464 10609201152 1253361744 13.269 95 20 40 1 1262557632 4754544 4441344
17:30:16 17694 8192000000 10131721312 10536361984 1189122800 12.675 94 23 46 0 1199928416 5670160 5135456
17:31:48 7584 8192000000 10141399584 10540638208 1205840048 12.831 95 30 60 0 1219041056 7043632 6157376
17:32:57 6950 8192000000 10147932592 10555846656 1250720304 13.245 99 27 55 2 1262736640 5490800 6525536
17:34:06 10330 8192000000 10163298912 10570833920 1257042816 13.303 99 13 26 0 1264819488 4861792 2914880
17:35:17 43545 8192000000 10163103088 10575867904 1274797792 13.465 100 24 48 2 1286508752 5242256 6468704
17:37:01 5603 8192000000 10189847936 10596999168 1287857328 13.585 100 40 79 0 1309268976 13185264 8226384
17:38:11 37033 8192000000 10218256512 10626281472 1312060624 13.805 100 26 52 0 1322839712 4716768 6062320
17:39:58 99368 8192000000 10247537056 10666156032 1344868304 14.101 100 55 110 1 1367540800 10342048 12330448
17:42:42 26546 8192000000 10275997312 10697367552 1362775968 14.262 100 41 81 0 1382401312 9332544 10292800
17:44:12 7882 8192000000 10293501552 10708029440 1376087392 14.382 100 52 101 1 1399979936 11318960 12573584
17:45:23 9613 8192000000 10304188480 10724765696 1384473376 14.457 100 46 92 0 1414352928 19139408 10740144
17:46:38 6668 8192000000 10308387280 10730147840 1377953136 14.398 99 52 104 1 1405088336 15236592 11898608
17:47:50 9330 8192000000 10315914368 10739961856 1396048176 14.560 100 52 104 0 1425939056 17701280 12189600
17:49:09 12145 8192000000 10330426832 10750083072 1400349280 14.598 100 69 138 2 1438252720 21300032 16603408
17:50:33 9030 8192000000 10341578352 10761658368 1406848208 14.656 100 62 124 0 1443212784 21754448 14610128
17:51:48 4805 8192000000 10344484320 10762924032 1407254592 14.660 100 75 150 2 1451982192 25482272 19245328
17:52:55 3414 8192000000 10327071968 10743767040 1382812848 14.442 99 79 158 2 1435212048 30659440 21739760
17:54:05 4715 8192000000 10335910608 10749247488 1387299760 14.482 100 63 125 2 1437506208 33886144 16320304
17:55:14 1786 8192000000 10342109408 10748907520 1389476272 14.501 100 89 173 2 1442277840 30407088 22394480
17:56:20 8448 8192000000 10311175520 10730336256 1356257840 14.204 97 50 100 2 1398075280 28417344 13400096
17:57:33 9318 8192000000 10337682096 10759344128 1392744880 14.530 100 52 104 1 1440538176 33574224 14219072
17:59:01 13080 8192000000 10293761440 10724466688 1344256000 14.096 97 32 62 2 1376969056 24381712 8331344
18:00:17 22061 8192000000 10290529088 10723565568 1331793712 13.983 97 31 61 1 1370557136 30772768 7990656
18:01:44 9839 8192000000 10304594896 10731618304 1360783936 14.244 100 43 86 1 1407547200 35205904 11557360
18:03:05 15432 8192000000 10266136880 10707427328 1348239984 14.132 100 31 61 0 1380541552 24504672 7796896
18:04:42 51030 8192000000 10244484576 10697351168 1305391072 13.744 97 6 12 0 1323417904 16613424 1413408
18:06:34 56937 8192000000 10271742592 10699841536 1336155344 14.023 98 26 52 1 1349302592 6446288 6700960
18:08:34 76615 8192000000 10269781152 10719481856 1299913536 13.694 95 11 22 0 1324608256 22069104 2625616
18:10:52 85236 8192000000 10254967856 10689675264 1275832944 13.475 94 7 12 1 1287340208 10388480 1118784
18:13:43 48420 8192000000 10282100800 10718478336 1352286976 14.168 99 35 70 0 1377852112 16946880 8618256
18:15:43 80728 8192000000 10278885376 10713440256 1336687392 14.028 98 16 31 1 1351640832 11585072 3368368
18:18:08 43624 8192000000 10297307376 10731417600 1372354912 14.348 100 41 82 1 1389957216 6979808 10622496
18:20:00 101793 8192000000 10328682496 10762555392 1391743664 14.521 100 27 54 0 1410507856 12287824 6476368
18:22:44 18383 8192000000 10330171440 10766954496 1404643344 14.636 100 43 84 2 1423138096 8625536 9869216
18:24:04 25754 8192000000 10326573904 10781331456 1407974016 14.666 100 42 85 0 1431611904 13090096 10547792
18:25:40 45931 8192000000 10313896240 10779979776 1397592928 14.574 99 12 24 0 1415582656 15175280 2814448
18:27:26 59444 8192000000 10306106208 10772426752 1377878928 14.398 98 16 32 2 1402987904 21275440 3833536
18:29:26 29974 8192000000 10308550160 10767130624 1383279168 14.446 99 38 76 0 1402548624 9964416 9305040
18:30:57 34182 8192000000 10322777808 10772193280 1403828528 14.629 100 30 58 1 1423179664 12563744 6787392
18:32:32 50364 8192000000 10302280048 10767372288 1396968464 14.568 100 7 13 0 1413643584 15374032 1301088
18:34:24 32977 8192000000 10298068976 10753261568 1363392896 14.268 98 27 54 0 1385125024 15282096 6450032
18:36:05 60160 8192000000 10313048112 10756198400 1345257104 14.105 97 26 52 0 1373360784 21840320 6263360
18:38:09 79452 8192000000 10284188768 10741985280 1348305552 14.132 98 10 20 1 1374814144 23520960 2987632
18:40:39 83963 8192000000 10275296656 10713391104 1321431392 13.890 97 13 25 2 1334734896 10515120 2788384
18:43:04 58166 8192000000 10282187376 10725646336 1369827472 14.326 100 34 67 3 1396716464 19038128 7850864
18:46:04 73197 8192000000 10272496192 10724954112 1348562576 14.135 99 30 59 3 1369487424 13891328 7033520
18:48:17 48531 8192000000 10268489264 10724900864 1345018368 14.103 98 20 40 1 1367738464 18401792 4318304
18:50:17 25131 8192000000 10263812656 10702012416 1340580080 14.063 99 25 50 1 1356631648 10295104 5756464
18:51:46 40806 8192000000 10228367984 10668240896 1276691872 13.483 95 12 24 0 1290849008 10429712 3727424
18:53:27 78835 8192000000 10268717680 10723545088 1345646864 14.108 99 16 32 1 1365174864 15619824 3908176
18:55:46 58197 8192000000 10274987808 10726334464 1348704976 14.136 99 17 34 0 1374868576 22254112 3909488
18:57:46 53398 8192000000 10282951136 10726539264 1339568192 14.054 98 23 46 0 1356144448 11063600 5512656
18:59:42 55514 8192000000 10276192928 10725068800 1353913568 14.183 99 13 26 0 1372923920 16217696 2792656
19:01:43 64982 8192000000 10265520816 10720366592 1346628848 14.117 99 20 40 1 1365409488 14244896 4535744
19:04:02 82364 8192000000 10296391344 10751275008 1367952400 14.309 100 12 24 0 1386409760 16025120 2432240
19:06:26 52984 8192000000 10292983024 10734096384 1360223936 14.239 99 13 25 1 1371548672 8454224 2870512
19:08:20 89906 8192000000 10284691568 10736095232 1366134512 14.292 99 27 54 0 1380712736 8273248 6304976
19:10:52 4583 8192000000 10300123760 10747838464 1378661776 14.405 100 30 60 1 1396457952 10808832 6987344
19:11:57 67002 8192000000 10304106880 10774016000 1396417568 14.563 100 17 34 1 1426247696 25839584 3990544
19:14:04 47894 8192000000 10298104048 10766745600 1395421216 14.554 100 14 29 1 1417126592 18329472 3375904
19:15:59 61005 8192000000 10302111664 10753896448 1373089312 14.355 98 19 38 0 1387054496 9363776 4601408
19:18:06 19043 8192000000 10290205824 10743615488 1373085472 14.355 99 22 44 1 1388826816 10561376 5179968
19:19:25 28676 8192000000 10314325728 10758168576 1380014976 14.417 99 35 70 0 1399765408 12172320 7578112
19:20:58 73924 8192000000 10298881248 10764279808 1367990944 14.309 98 15 30 1 1387583968 15711008 3882016
19:23:14 9510 8192000000 10302801936 10733776896 1375189712 14.374 100 63 125 2 1397466592 8108320 14168560
19:24:26 48376 8192000000 10276404320 10719629312 1341972304 14.075 99 25 49 0 1357883936 10650688 5260944
19:26:23 73053 8192000000 10269633920 10717917184 1322239232 13.897 97 10 20 1 1334847216 10486240 2121744
19:28:37 112230 8192000000 10279744480 10727239680 1339256240 14.051 98 11 22 0 1352598336 10709568 2632528
19:31:36 17053 8192000000 10289391312 10733219840 1373092832 14.355 100 36 72 0 1394042960 13172912 7777216
19:32:56 64595 8192000000 10241142656 10694385664 1324591712 13.918 98 7 14 0 1336420528 10120640 1708176
19:35:02 87118 8192000000 10243750144 10686091264 1273707344 13.456 94 15 30 0 1286244064 8693072 3843648
19:37:29 41592 8192000000 9507224064 9847402496 640496304 7.251 48 3 6 0 651302752 10437568 368880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-01-2022 19:39:45 to 12-01-2022 19:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 160 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2022 19:40:01 to 12-01-2022 19:40:03.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          248500
MON$OLDEST_ACTIVE               248747
MON$OLDEST_SNAPSHOT             248747
MON$NEXT_TRANSACTION            248747
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-03 16:41:55.3470
MON$PAGES                       2044064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
--- end of diff output ---

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

12.01.22 19:40:04. There are 3 logs in /var/tmp/logs-oltp30 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
2022-01-12 19:40:07 No severe PSQL-related problems occured
12.01.22 19:40:08. End of report.