Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2022 22: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 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 15220768 kB
MemAvailable 30433684 kB
Buffers 0 kB
Cached 16471188 kB
SwapCached 2272 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.11
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-10-24 22:38:32
DTS_END                         2022-10-24 22:38:32.7920
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(57:17)
IP                              ::1/54926
TRN_ID                          246428
ATT_ID                          716
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: 9585.17 24293 1150220
customer order: creation 173.78 384 20853
customer order: refuse 14.87 347 1784
order to supplier: creation 46.83 1255 5620
order to supplier: removal 3.54 97420 425
invoice (draft): creation 46.27 3387 5552
invoice (draft): removal 55.53 714 6664
invoice accept: apply 42.30 16732 5076
invoice accept: cancel 50.08 825 6010
customer reserve: creation 3915.46 164 469855
customer reserve: removal 4938.15 55 592578
realization accept: apply 85.24 178 10229
realization accept: cancel 82.76 107 9931
payment from customer: creation 59.86 115 7183
payment from customer: removal 6.03 80 724
payment to supplier: creation 49.25 123 5910
payment to supplier: removal 3.43 82 411
service: total inventory turnovers 4.53 1210 544
service: total monetary turnovers 6.47 34 776
service: aggregate perf. data 0.76 24740 91
service: refresh index statistics 0.03 337908 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2022 22:39:10 to 24-10-2022 22:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3197
TEST_TIME 2 2936
TEST_TIME 3 3570
TEST_TIME 4 3678
TEST_TIME 5 4431
TEST_TIME 6 4495
TEST_TIME 7 5043
TEST_TIME 8 4990
TEST_TIME 9 5069
TEST_TIME 10 5628
TEST_TIME 11 6035
TEST_TIME 12 7022
TEST_TIME 13 7581
TEST_TIME 14 7506
TEST_TIME 15 8176
TEST_TIME 16 7968
TEST_TIME 17 8047
TEST_TIME 18 8157
TEST_TIME 19 8198
TEST_TIME 20 8700
TEST_TIME 21 8890
TEST_TIME 22 8869
TEST_TIME 23 9438
TEST_TIME 24 9491
TEST_TIME 25 9468
TEST_TIME 26 9715
TEST_TIME 27 9684
TEST_TIME 28 9835
TEST_TIME 29 10133
TEST_TIME 30 10641
TEST_TIME 31 11007
TEST_TIME 32 10855
TEST_TIME 33 10702
TEST_TIME 34 10624
TEST_TIME 35 10426
TEST_TIME 36 10327
TEST_TIME 37 10145
TEST_TIME 38 10257
TEST_TIME 39 10274
TEST_TIME 40 10077
TEST_TIME 41 9972
TEST_TIME 42 9873
TEST_TIME 43 9763
TEST_TIME 44 9782
TEST_TIME 45 9851
TEST_TIME 46 9726
TEST_TIME 47 9859
TEST_TIME 48 9814
TEST_TIME 49 9708
TEST_TIME 50 9648
TEST_TIME 51 9585
TEST_TIME 52 9561
TEST_TIME 53 9457
TEST_TIME 54 9404
TEST_TIME 55 9440
TEST_TIME 56 9543
TEST_TIME 57 9554
TEST_TIME 58 9543
TEST_TIME 59 9491
TEST_TIME 60 9614
TEST_TIME 61 9716
TEST_TIME 62 9624
TEST_TIME 63 9814
TEST_TIME 64 9903
TEST_TIME 65 9935
TEST_TIME 66 10028
TEST_TIME 67 10089
TEST_TIME 68 10010
TEST_TIME 69 10031
TEST_TIME 70 9954
TEST_TIME 71 9910
TEST_TIME 72 9957
TEST_TIME 73 9868
TEST_TIME 74 9834
TEST_TIME 75 9864
TEST_TIME 76 9841
TEST_TIME 77 9815
TEST_TIME 78 9770
TEST_TIME 79 9740
TEST_TIME 80 9676
TEST_TIME 81 9692
TEST_TIME 82 9702
TEST_TIME 83 9668
TEST_TIME 84 9644
TEST_TIME 85 9602
TEST_TIME 86 9526
TEST_TIME 87 9494
TEST_TIME 88 9456
TEST_TIME 89 9419
TEST_TIME 90 9430
TEST_TIME 91 9504
TEST_TIME 92 9520
TEST_TIME 93 9524
TEST_TIME 94 9607
TEST_TIME 95 9665
TEST_TIME 96 9630
TEST_TIME 97 9658
TEST_TIME 98 9652
TEST_TIME 99 9698
TEST_TIME 100 9708
TEST_TIME 101 9710
TEST_TIME 102 9669
TEST_TIME 103 9622
TEST_TIME 104 9618
TEST_TIME 105 9587
TEST_TIME 106 9572
TEST_TIME 107 9529
TEST_TIME 108 9529
TEST_TIME 109 9534
TEST_TIME 110 9521
TEST_TIME 111 9491
TEST_TIME 112 9504
TEST_TIME 113 9531
TEST_TIME 114 9580
TEST_TIME 115 9536
TEST_TIME 116 9595
TEST_TIME 117 9570
TEST_TIME 118 9617
TEST_TIME 119 9598
TEST_TIME 120 9585

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2022 22:39:14 to 24-10-2022 22:39:17.

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 1586318 1586318 0 0.00 0 17537 6 0 0
doc_list_biud 1586318 1586318 0 0.00 0 59939 26 0 0
sp_add_invoice_to_stock 5243 5076 167 3.18 44 152605 16733 0 167
sp_cancel_adding_invoice 6010 6010 0 0.00 4 49642 826 0 0
sp_cancel_client_order 1784 1784 0 0.00 4 8807 347 0 0
sp_cancel_customer_reserve 592578 592578 0 0.00 0 59942 56 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 4290 80 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 2 3779 83 0 0
sp_cancel_supplier_invoice 6686 6664 22 0.32 2 19785 715 0 22
sp_cancel_supplier_order 425 425 0 0.00 5 825328 97420 0 0
sp_cancel_write_off 9931 9931 0 0.00 1 14738 108 0 0
sp_client_order 20853 20853 0 0.00 3 53376 384 0 0
sp_customer_reserve 469855 469855 0 0.00 0 49655 164 0 0
sp_fill_shopping_cart 32015 32015 0 0.00 1 17327 39 0 0
sp_fill_shopping_cart_clo_res 469854 469854 0 0.00 0 18147 16 0 0
sp_get_clo_for_invoice 5075 5075 0 0.00 0 34350 349 0 0
sp_kill_cost_storno 17076 17076 0 0.00 0 14735 53 0 0
sp_kill_qstorno_ret_qs2qd 1202176 1202176 0 0.00 0 59937 30 0 0
sp_kill_qty_storno 632696 632696 0 0.00 0 59939 65 0 0
sp_lock_selected_doc 21121 21121 0 0.00 0 7197 8 0 0
sp_make_cost_storno 28397 28397 0 0.00 0 14936 57 0 0
sp_multiply_rows_for_pdistr 28397 28397 0 0.00 0 6904 25 0 0
sp_multiply_rows_for_qdistr 20853 20853 0 0.00 0 53356 193 0 0
sp_pay_from_customer 7183 7183 0 0.00 4 13790 116 0 0
sp_pay_to_supplier 5910 5910 0 0.00 5 15089 123 0 0
sp_payment_common 13093 13093 0 0.00 2 8942 27 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 8801 314 0 0
sp_qd_handle_on_invoice_upd_sts 11085 11085 0 0.00 0 23614 323 0 0
sp_qd_handle_on_reserve_upd_sts 10229 10229 0 0.00 0 15077 57 0 0
sp_reserve_write_off 10229 10229 0 0.00 6 16249 179 0 0
sp_supplier_invoice 5552 5552 0 0.00 5 74868 3388 0 0
sp_supplier_order 5620 5620 0 0.00 5 20155 1255 0 0
srv_aggregate_perf_data 91 91 0 0.00 2251 333697 24740 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1389 134833 45679 0 0
srv_find_qd_qs_mism 1102397 1102397 0 0.00 0 17536 4 0 0
srv_make_invnt_saldo 544 544 0 0.00 18 67533 1210 0 0
srv_make_money_saldo 776 776 0 0.00 1 3573 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 145568 466143 337909 0 0
t$perf-abend:sp_add_invoice_to_stock 167 167 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 22 22 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5076 5076 0 0.00 0 17921 195 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 6989 98 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 4083 19 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 3613 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 1565 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 158 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 6695 42 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 31016 1724 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 1238 5 0 0
t$perf-norm:sp_client_order 20853 20853 0 0.00 0 7403 18 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 3756 16 0 0
t$perf-norm:sp_pay_from_customer 7183 7183 0 0.00 0 7429 16 0 0
t$perf-norm:sp_pay_to_supplier 5910 5910 0 0.00 0 10862 14 0 0
t$perf-norm:sp_reserve_write_off 10229 10229 0 0.00 0 13055 14 0 0
t$perf-norm:sp_supplier_invoice 5552 5552 0 0.00 0 8301 21 0 0
t$perf-norm:sp_supplier_order 5620 5620 0 0.00 0 10808 19 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 891 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 1764 24 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 10247 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10484 10150 334 3.18 0 6320 20 0 334
v_all_customers 37934 37934 0 0.00 0 62 1 0 0
v_all_suppliers 30878 30878 0 0.00 0 74 1 0 0
v_all_wares 41704 41704 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 4614 24 0 0
v_cancel_client_order 3568 3568 0 0.00 0 5012 7 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 3819 11 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 2196 7 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 3770 10 0 0
v_cancel_supplier_invoice 850 806 44 5.17 0 885 17 0 44
v_cancel_supplier_order 850 850 0 0.00 0 844 4 0 0
v_cancel_write_off 2016 2016 0 0.00 0 3061 19 0 0
v_max_id_clo_ord 5620 5620 0 0.00 0 3868 3 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 3174 20 0 0
v_max_id_ord_sup 5542 5542 0 0.00 0 3641 4 0 0
v_max_non_paid_invoice 1633 1633 0 0.00 0 1889 4 0 0
v_max_non_paid_realizn 3806 3806 0 0.00 0 1890 5 0 0
v_min_id_clo_ord 5620 5620 0 0.00 0 6805 9 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 9191 18 0 0
v_min_id_ord_sup 5542 5542 0 0.00 0 5433 7 0 0
v_min_non_paid_invoice 5910 5910 0 0.00 0 4086 16 0 0
v_min_non_paid_realizn 7183 7183 0 0.00 0 7219 23 0 0
v_our_firm 10524 10524 0 0.00 0 17 1 0 0
v_reserve_write_off 20458 20458 0 0.00 0 4350 7 0 0
x_lock_depdocs_on_canc_invoice 12271 12271 0 0.00 0 28057 114 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 17197 422 0 0
x_make_qstorno_1000_1200 5620 5620 0 0.00 2 20142 1176 0 0
x_make_qstorno_1200_2000 5552 5552 0 0.00 2 74811 3247 0 0
x_make_qstorno_2100_3300 469855 469855 0 0.00 0 49653 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2022 22:39:18 to 24-10-2022 22:39:22.

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
20:08:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 23128 8192000000 9900668304 10210160640 982299936 10.707 100 88 176 0 1010607120 5352512 22954672
20:11:12 24709 8192000000 9959743200 10281517056 1037978464 11.245 100 93 186 0 1069596880 7962128 23656288
20:12:43 17110 8192000000 9982001328 10316271616 1069923904 11.551 100 84 167 0 1100040176 9705904 20410368
20:14:02 10020 8192000000 10045150976 10395348992 1129291856 12.115 100 73 146 0 1156516352 10215056 17009440
20:15:17 3304 8192000000 10093680112 10454007808 1182007952 12.609 100 82 162 0 1212828624 12497584 18323088
20:16:22 3703 8192000000 10136446560 10507718656 1224976000 13.008 100 82 162 2 1256350064 13476752 17897312
20:17:27 1970 8192000000 10175462528 10552434688 1260093712 13.331 100 85 170 1 1292091920 13278656 18719552
20:18:31 1462 8192000000 10197621632 10582274048 1285431664 13.563 100 93 187 0 1318668960 12147936 21089360
20:19:33 20805 8192000000 10216949808 10607005696 1305372528 13.744 100 70 140 1 1331665360 10948352 15344480
20:20:55 18691 8192000000 10189877824 10606166016 1306932464 13.758 100 19 38 0 1317956640 6456224 4567952
20:22:15 7942 8192000000 10210011568 10624716800 1318978096 13.867 100 18 36 0 1333281968 10193696 4110176
20:23:24 10275 8192000000 10208470976 10626715648 1326020512 13.931 100 15 30 1 1335051584 5597168 3433904
20:24:37 25242 8192000000 10224036880 10645639168 1329725008 13.965 100 23 45 1 1344706240 10096944 4884288
20:26:13 4761 8192000000 10227366848 10649456640 1332526320 13.990 100 34 67 0 1351565296 11780768 7258208
20:27:18 8808 8192000000 10217133424 10635681792 1318464112 13.863 99 15 30 0 1327154624 5186640 3503872
20:28:28 8181 8192000000 10183748464 10601672704 1265911152 13.384 96 13 25 0 1273491008 4883152 2696704
20:29:36 30271 8192000000 10157295440 10572562432 1227056480 13.027 95 26 50 0 1238088368 4873472 6158416
20:31:08 7305 8192000000 10146466448 10544209920 1218159552 12.945 97 27 54 1 1229851040 5437792 6253696
20:32:20 6294 8192000000 10137675904 10533924864 1234575136 13.096 99 26 52 0 1245700304 5234656 5890512
20:33:27 68365 8192000000 10181284480 10593579008 1278647968 13.501 99 31 62 1 1295798736 9722288 7428480
20:35:36 8734 8192000000 10190503808 10604081152 1296691968 13.665 100 25 50 0 1307796640 5136288 5968384
20:36:48 14171 8192000000 10202012496 10612637696 1306339344 13.753 100 12 24 1 1313353984 4905632 2109008
20:38:09 11927 8192000000 10223788992 10640293888 1328219424 13.951 100 29 57 1 1344280976 9934976 6126576
20:39:23 29880 8192000000 10244786048 10663882752 1347704336 14.127 100 42 82 1 1362463216 5532064 9226816
20:41:00 16334 8192000000 10271195328 10689921024 1367894080 14.308 100 51 97 0 1388965904 10164288 10907536
20:42:26 37223 8192000000 10288602480 10712965120 1383245552 14.446 100 43 86 0 1407912880 15216224 9451104
20:44:08 27112 8192000000 10298854864 10722701312 1376907792 14.389 99 52 102 2 1405206832 16029488 12269552
20:45:51 22968 8192000000 10307903616 10733539328 1387079792 14.480 99 39 78 0 1413560480 17301616 9179072
20:47:20 12277 8192000000 10313771440 10733019136 1385908880 14.469 99 55 110 0 1420346304 20744608 13692816
20:48:44 5645 8192000000 10331238096 10745438208 1394072800 14.542 100 65 128 2 1437715120 27906448 15735872
20:49:56 1958 8192000000 10320618416 10733662208 1372871776 14.353 99 74 146 1 1420964848 30224272 17868800
20:51:01 1389 8192000000 10339544304 10751631360 1395879184 14.558 100 71 138 1 1437533488 24496512 17157792
20:52:08 14116 8192000000 10304146112 10725007360 1367673632 14.306 99 51 103 1 1406994128 26581968 12738528
20:53:28 4979 8192000000 10319084064 10738286592 1381785424 14.433 100 55 108 2 1425759728 30444272 13530032
20:54:36 13027 8192000000 10293293408 10701271040 1312878768 13.812 96 59 112 2 1347022064 21245168 12898128
20:55:57 3344 8192000000 10334232160 10741960704 1384737152 14.459 100 79 157 0 1433516432 29057168 19722112
20:57:05 1409 8192000000 10316170224 10719707136 1364854512 14.281 100 91 180 1 1418704368 31153072 22696784
20:58:11 5379 8192000000 10314869680 10730582016 1371494112 14.340 100 52 103 1 1416908208 31882032 13532064
20:59:25 8710 8192000000 10313775344 10736885760 1379062240 14.408 100 46 87 0 1419182912 28931920 11188752
21:00:51 6366 8192000000 10322438384 10740527104 1378175408 14.400 100 54 106 1 1426894688 35137616 13581664
21:02:02 14548 8192000000 10302095056 10727157760 1361997696 14.255 99 33 65 0 1399556080 29075376 8483008
21:03:21 9306 8192000000 10296714944 10728906752 1358533408 14.224 99 33 66 1 1404624896 37618208 8473280
21:04:38 11770 8192000000 10310905248 10748813312 1359656592 14.234 99 18 36 0 1404401568 39724384 5020592
21:05:52 18345 8192000000 10325052944 10756575232 1380829808 14.424 100 28 56 2 1426591952 38321776 7440368
21:07:15 28782 8192000000 10316049472 10755055616 1383177536 14.445 100 24 48 0 1421927440 32273488 6476416
21:08:46 32806 8192000000 10301823824 10744487936 1367249664 14.302 99 19 39 0 1394680640 22883824 4547152
21:10:20 28808 8192000000 10302133712 10739228672 1373591008 14.359 99 25 48 2 1388024688 9447504 4986176
21:11:53 111182 8192000000 10296808640 10742435840 1368458224 14.313 98 31 59 1 1389809072 14203888 7146960
21:14:51 119715 8192000000 10287911536 10752630784 1373343056 14.357 99 21 42 0 1394169200 16087024 4739120
21:17:51 51172 8192000000 10286766992 10743578624 1365074400 14.283 98 12 23 0 1383557600 15814496 2668704
21:19:43 67095 8192000000 10310665792 10768080896 1395092672 14.551 100 15 30 0 1419102304 20439376 3570256
21:21:51 76914 8192000000 10311128208 10783219712 1402808944 14.620 100 15 30 0 1434432608 27939088 3684576
21:24:13 89265 8192000000 10314473024 10775912448 1408970160 14.675 100 26 49 0 1435538672 20364912 6203600
21:26:51 15736 8192000000 10298392160 10754576384 1393963136 14.541 99 27 54 0 1409831648 10074048 5794464
21:28:34 87452 8192000000 10293652816 10763354112 1377034032 14.390 99 12 24 0 1399766432 20018080 2714320
21:31:03 82387 8192000000 10313848704 10780827648 1407950464 14.666 100 24 47 0 1429581968 16241552 5389952
21:33:56 56528 8192000000 10301611904 10782351360 1386827520 14.478 99 11 22 0 1410424336 20814704 2782112
21:36:02 44710 8192000000 10308198576 10769809408 1395585424 14.556 99 15 30 0 1410955888 11788432 3582032
21:37:47 33042 8192000000 10302061824 10753040384 1375690368 14.378 98 43 84 1 1398388400 12796384 9901648
21:39:36 108636 8192000000 10277971584 10751438848 1311181328 13.797 94 11 22 0 1333167568 19505024 2481216
21:42:25 134832 8192000000 10270334400 10735857664 1253170464 13.267 90 11 22 0 1273332064 18211280 1950320
21:45:40 51769 8192000000 10271466752 10716266496 1317008480 13.850 96 14 28 1 1335124416 14994928 3121008
21:47:32 46213 8192000000 10253262336 10690809856 1323041504 13.904 97 43 86 1 1343526880 10552800 9932576
21:49:24 96795 8192000000 10255080432 10709610496 1333534976 13.999 98 27 54 0 1350564224 9469824 7559424
21:52:02 30353 8192000000 10256534736 10702323712 1352332208 14.168 100 13 26 0 1367554800 12572000 2650592
21:53:34 82977 8192000000 10230567920 10680193024 1301634208 13.710 96 13 26 0 1315333584 10720144 2979232
21:55:58 42445 8192000000 10233179248 10686869504 1342225616 14.077 100 12 25 0 1352307744 7645664 2436464
21:57:41 49834 8192000000 10251256944 10700455936 1352049280 14.166 100 29 58 0 1369469488 10214080 7206128
21:59:34 77639 8192000000 10253103216 10706063360 1357024496 14.211 100 17 34 1 1370400256 9083600 4292160
22:02:06 40108 8192000000 10240336960 10697723904 1351541456 14.161 100 8 16 0 1361042352 7995072 1505824
22:03:48 73985 8192000000 10228203008 10681487360 1335012976 14.012 99 7 14 1 1343182992 6816240 1353776
22:06:02 53081 8192000000 10247778832 10703343616 1320439808 13.881 97 8 16 0 1331035696 8570944 2024944
22:07:59 60173 8192000000 10267372912 10713837568 1329297488 13.961 97 17 34 0 1344129344 11085968 3745888
22:10:00 60665 8192000000 10277908336 10735149056 1374039520 14.363 100 17 34 0 1387767856 10461600 3266736
22:12:03 41030 8192000000 10288720320 10747990016 1384641824 14.458 100 24 48 0 1404053008 14281968 5129216
22:14:21 27595 8192000000 10303317616 10738790400 1362666384 14.261 98 37 73 0 1378553872 8020016 7867472
22:15:51 43658 8192000000 10283323200 10731139072 1358541744 14.224 98 14 28 0 1372906320 11340352 3024224
22:17:35 89186 8192000000 10297253136 10747039744 1353219392 14.176 97 16 28 1 1367166048 10926784 3019872
22:20:16 95629 8192000000 10294216288 10748829696 1377737168 14.396 99 23 47 0 1389885824 6699584 5449072
22:23:08 35997 8192000000 10298545328 10752057344 1381586320 14.431 99 14 28 0 1394661008 10143488 2931200
22:24:44 63582 8192000000 10272228864 10726281216 1362106672 14.256 98 15 30 1 1379778368 14246160 3425536
22:26:48 71723 8192000000 10270770304 10740228096 1369421104 14.322 99 13 26 0 1382979152 10492944 3065104
22:29:10 23535 8192000000 10305724272 10744320000 1361663424 14.252 98 44 86 1 1380668960 8467344 10538192
22:30:40 45166 8192000000 10287486560 10744406016 1371307904 14.339 99 27 52 1 1394408208 16801072 6299232
22:32:47 59867 8192000000 10254945152 10685366272 1222679648 12.986 90 19 38 0 1241462144 14648512 4133984
22:34:48 55598 8192000000 10249121248 10681434112 1304819200 13.739 98 20 39 0 1322357312 13807088 3731024
22:37:16 69639 8192000000 10259135376 10706108416 1322071904 13.895 98 25 50 0 1339020624 11117536 5831184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-10-2022 22:39:24 to 24-10-2022 22:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 167 v_add_invoice_to_stock 335544517
except 22 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2022 22:39:40 to 24-10-2022 22:39:42.

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          245065
MON$OLDEST_ACTIVE               246626
MON$OLDEST_SNAPSHOT             246626
MON$NEXT_TRANSACTION            246626
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                       2048224
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:172: 
:173: mscs-test-161.budzdorov.ru    Mon Oct 24 22:38:39 2022
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:175: 
:176: 
:177: mscs-test-161.budzdorov.ru    Mon Oct 24 22:38:41 2022
:178:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:179: 
:180: 
:181: mscs-test-161.budzdorov.ru    Mon Oct 24 22:38:42 2022
:182:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:183: 
--- end of diff output ---

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

24.10.22 22:39:43. 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-10-24 22:39:46 No severe PSQL-related problems occured
24.10.22 22:39:47. End of report.