Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2022 01: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 15572816 kB
MemAvailable 30525444 kB
Buffers 0 kB
Cached 16125920 kB
SwapCached 2204 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-07 01:38:29
DTS_END                         2022-10-07 01:38:29.3710
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(57:17)
IP                              ::1/36648
TRN_ID                          239107
ATT_ID                          675
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: 9399.61 22113 1127953
customer order: creation 167.83 378 20140
customer order: refuse 14.11 345 1693
order to supplier: creation 44.35 1228 5322
order to supplier: removal 3.43 95770 412
invoice (draft): creation 46.24 3428 5549
invoice (draft): removal 55.28 686 6633
invoice accept: apply 40.87 16925 4904
invoice accept: cancel 49.88 866 5986
customer reserve: creation 3798.23 165 455788
customer reserve: removal 4888.13 53 586576
realization accept: apply 82.64 200 9917
realization accept: cancel 81.30 101 9756
payment from customer: creation 58.20 149 6984
payment from customer: removal 5.75 68 690
payment to supplier: creation 47.97 157 5756
payment to supplier: removal 3.33 91 400
service: total inventory turnovers 4.52 1097 542
service: total monetary turnovers 6.78 26 813
service: aggregate perf. data 0.73 23699 88
service: refresh index statistics 0.03 296834 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2022 01:39:16 to 07-10-2022 01:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2669
TEST_TIME 2 3368
TEST_TIME 3 3917
TEST_TIME 4 4103
TEST_TIME 5 4201
TEST_TIME 6 4690
TEST_TIME 7 4653
TEST_TIME 8 4906
TEST_TIME 9 5212
TEST_TIME 10 5402
TEST_TIME 11 5393
TEST_TIME 12 5427
TEST_TIME 13 6017
TEST_TIME 14 6106
TEST_TIME 15 6414
TEST_TIME 16 6572
TEST_TIME 17 6896
TEST_TIME 18 7403
TEST_TIME 19 8264
TEST_TIME 20 8681
TEST_TIME 21 8824
TEST_TIME 22 8838
TEST_TIME 23 9165
TEST_TIME 24 9047
TEST_TIME 25 9320
TEST_TIME 26 9652
TEST_TIME 27 9693
TEST_TIME 28 10039
TEST_TIME 29 9796
TEST_TIME 30 9745
TEST_TIME 31 9446
TEST_TIME 32 10354
TEST_TIME 33 10179
TEST_TIME 34 9964
TEST_TIME 35 9814
TEST_TIME 36 9867
TEST_TIME 37 9733
TEST_TIME 38 9706
TEST_TIME 39 9818
TEST_TIME 40 9667
TEST_TIME 41 9496
TEST_TIME 42 9374
TEST_TIME 43 9578
TEST_TIME 44 9481
TEST_TIME 45 9385
TEST_TIME 46 9291
TEST_TIME 47 9217
TEST_TIME 48 9269
TEST_TIME 49 9266
TEST_TIME 50 9204
TEST_TIME 51 9213
TEST_TIME 52 9115
TEST_TIME 53 8979
TEST_TIME 54 8859
TEST_TIME 55 8980
TEST_TIME 56 8942
TEST_TIME 57 8996
TEST_TIME 58 9037
TEST_TIME 59 9038
TEST_TIME 60 9062
TEST_TIME 61 9084
TEST_TIME 62 9079
TEST_TIME 63 9119
TEST_TIME 64 9130
TEST_TIME 65 9244
TEST_TIME 66 9319
TEST_TIME 67 9312
TEST_TIME 68 9358
TEST_TIME 69 9489
TEST_TIME 70 9465
TEST_TIME 71 9433
TEST_TIME 72 9335
TEST_TIME 73 9315
TEST_TIME 74 9262
TEST_TIME 75 9257
TEST_TIME 76 9251
TEST_TIME 77 9223
TEST_TIME 78 9167
TEST_TIME 79 9186
TEST_TIME 80 9165
TEST_TIME 81 9195
TEST_TIME 82 9169
TEST_TIME 83 9179
TEST_TIME 84 9164
TEST_TIME 85 9220
TEST_TIME 86 9277
TEST_TIME 87 9305
TEST_TIME 88 9337
TEST_TIME 89 9295
TEST_TIME 90 9337
TEST_TIME 91 9338
TEST_TIME 92 9421
TEST_TIME 93 9428
TEST_TIME 94 9445
TEST_TIME 95 9453
TEST_TIME 96 9521
TEST_TIME 97 9470
TEST_TIME 98 9428
TEST_TIME 99 9411
TEST_TIME 100 9386
TEST_TIME 101 9415
TEST_TIME 102 9399
TEST_TIME 103 9393
TEST_TIME 104 9361
TEST_TIME 105 9343
TEST_TIME 106 9335
TEST_TIME 107 9295
TEST_TIME 108 9298
TEST_TIME 109 9244
TEST_TIME 110 9200
TEST_TIME 111 9272
TEST_TIME 112 9267
TEST_TIME 113 9274
TEST_TIME 114 9264
TEST_TIME 115 9278
TEST_TIME 116 9352
TEST_TIME 117 9307
TEST_TIME 118 9370
TEST_TIME 119 9369
TEST_TIME 120 9402

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-10-2022 01:39:20 to 07-10-2022 01:39:23.

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 1550848 1550848 0 0.00 0 21831 6 0 0
doc_list_biud 1550848 1550848 0 0.00 0 70228 26 0 0
sp_add_invoice_to_stock 4987 4904 83 1.66 24 171828 16926 0 83
sp_cancel_adding_invoice 5986 5986 0 0.00 4 67806 867 0 0
sp_cancel_client_order 1693 1693 0 0.00 4 9873 346 0 0
sp_cancel_customer_reserve 586576 586576 0 0.00 0 70231 53 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 4477 69 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 2 4550 92 0 0
sp_cancel_supplier_invoice 6636 6633 3 0.04 1 15671 687 0 3
sp_cancel_supplier_order 412 412 0 0.00 6 1015052 95770 0 0
sp_cancel_write_off 9756 9756 0 0.00 1 14187 102 0 0
sp_client_order 20140 20140 0 0.00 3 63787 378 0 0
sp_customer_reserve 455788 455788 0 0.00 0 42117 165 0 0
sp_fill_shopping_cart 31005 31005 0 0.00 1 24100 41 0 0
sp_fill_shopping_cart_clo_res 455784 455784 0 0.00 0 11831 15 0 0
sp_get_clo_for_invoice 4899 4899 0 0.00 0 25464 377 0 0
sp_kill_cost_storno 16832 16832 0 0.00 0 14184 54 0 0
sp_kill_qstorno_ret_qs2qd 1189953 1189953 0 0.00 0 70224 29 0 0
sp_kill_qty_storno 625872 625872 0 0.00 0 70227 63 0 0
sp_lock_selected_doc 20443 20443 0 0.00 0 8023 5 0 0
sp_make_cost_storno 27556 27556 0 0.00 0 14291 57 0 0
sp_multiply_rows_for_pdistr 27556 27556 0 0.00 0 19023 30 0 0
sp_multiply_rows_for_qdistr 20140 20140 0 0.00 0 63780 178 0 0
sp_pay_from_customer 6984 6984 0 0.00 3 21266 149 0 0
sp_pay_to_supplier 5756 5756 0 0.00 5 21230 157 0 0
sp_payment_common 12740 12740 0 0.00 1 18474 48 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 9867 308 0 0
sp_qd_handle_on_invoice_upd_sts 10885 10885 0 0.00 0 38071 350 0 0
sp_qd_handle_on_reserve_upd_sts 9917 9917 0 0.00 0 13166 65 0 0
sp_reserve_write_off 9917 9917 0 0.00 6 21879 201 0 0
sp_supplier_invoice 5549 5549 0 0.00 6 84644 3428 0 0
sp_supplier_order 5322 5322 0 0.00 5 71003 1229 0 0
srv_aggregate_perf_data 88 88 0 0.00 3051 315301 23700 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1675 113421 45075 0 0
srv_find_qd_qs_mism 1081449 1081449 0 0.00 0 15623 5 0 0
srv_make_invnt_saldo 542 542 0 0.00 34 40039 1097 0 0
srv_make_money_saldo 813 813 0 0.00 1 2651 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 174199 439227 296834 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 0 9795 160 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 5168 109 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 16471 25 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 2220 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 1622 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 3483 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 2372 16 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 28229 1832 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 3479 13 0 0
t$perf-norm:sp_client_order 20140 20140 0 0.00 0 5679 16 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 5448 14 0 0
t$perf-norm:sp_pay_from_customer 6984 6984 0 0.00 0 11610 15 0 0
t$perf-norm:sp_pay_to_supplier 5756 5756 0 0.00 0 8477 16 0 0
t$perf-norm:sp_reserve_write_off 9917 9917 0 0.00 0 16842 12 0 0
t$perf-norm:sp_supplier_invoice 5549 5549 0 0.00 0 5589 19 0 0
t$perf-norm:sp_supplier_order 5322 5322 0 0.00 0 11364 19 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 154 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 2862 31 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 1071 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9964 9798 166 1.66 0 19390 25 0 166
v_all_customers 36668 36668 0 0.00 0 29 1 0 0
v_all_suppliers 30094 30094 0 0.00 0 107 1 0 0
v_all_wares 40280 40280 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 7054 19 0 0
v_cancel_client_order 3386 3386 0 0.00 0 8766 10 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 2222 8 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 784 4 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 1732 5 0 0
v_cancel_supplier_invoice 750 744 6 0.80 0 1749 18 0 6
v_cancel_supplier_order 824 824 0 0.00 0 471 4 0 0
v_cancel_write_off 1912 1912 0 0.00 0 9219 19 0 0
v_max_id_clo_ord 5321 5321 0 0.00 0 953 2 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 2404 15 0 0
v_max_id_ord_sup 5544 5544 0 0.00 0 4938 5 0 0
v_max_non_paid_invoice 1574 1574 0 0.00 0 4062 8 0 0
v_max_non_paid_realizn 3733 3733 0 0.00 0 4262 6 0 0
v_min_id_clo_ord 5321 5321 0 0.00 0 5957 13 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 5999 14 0 0
v_min_id_ord_sup 5544 5544 0 0.00 0 7486 8 0 0
v_min_non_paid_invoice 5756 5756 0 0.00 0 18415 31 0 0
v_min_non_paid_realizn 6984 6984 0 0.00 0 18467 41 0 0
v_our_firm 10114 10114 0 0.00 0 19 1 0 0
v_reserve_write_off 19834 19834 0 0.00 0 19442 9 0 0
x_lock_depdocs_on_canc_invoice 12247 12247 0 0.00 0 19598 118 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 7665 417 0 0
x_make_qstorno_1000_1200 5322 5322 0 0.00 1 70997 1148 0 0
x_make_qstorno_1200_2000 5549 5549 0 0.00 2 80742 3278 0 0
x_make_qstorno_2100_3300 455788 455788 0 0.00 0 42115 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2022 01:39:24 to 07-10-2022 01:39:28.

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
23:08:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:34 23720 8192000000 9887836960 10193580032 966581904 10.553 100 86 172 0 994800720 5449632 22769184
23:11:05 29382 8192000000 9938447056 10256822272 1019878912 11.071 100 89 178 0 1051019344 8312368 22828064
23:12:36 12764 8192000000 9971444992 10298433536 1053838208 11.397 100 86 171 0 1084480608 9210144 21432256
23:13:59 12694 8192000000 10036711568 10384605184 1122972176 12.055 100 68 136 0 1148349184 9377728 15999280
23:15:15 4249 8192000000 10088720640 10444783616 1173177760 12.527 100 87 172 0 1206169808 12779984 20212064
23:16:23 3847 8192000000 10127264048 10492452864 1212605488 12.893 100 80 160 1 1243589632 13083024 17901120
23:17:28 1850 8192000000 10154062480 10527916032 1244546080 13.188 100 89 178 0 1278686768 14079504 20061184
23:18:32 1856 8192000000 10184259104 10566701056 1268519888 13.408 100 74 148 1 1297966208 13676352 15769968
23:19:35 1296 8192000000 10208029904 10592714752 1294889792 13.649 100 82 164 2 1326189840 12678048 18622000
23:20:37 18238 8192000000 10194855600 10598944768 1302979008 13.722 100 34 65 1 1317306480 6861776 7465696
23:21:56 5959 8192000000 10200611936 10615353344 1313397648 13.817 100 20 40 0 1328556720 10999200 4159872
23:23:05 12793 8192000000 10209991472 10629500928 1325919936 13.930 100 14 28 0 1338475504 9487152 3068416
23:24:21 71129 8192000000 10244376384 10652602368 1340123504 14.059 100 39 78 1 1354375904 4726544 9525856
23:26:34 8834 8192000000 10211242592 10633830400 1328068192 13.950 99 21 42 1 1339200448 6185680 4946576
23:27:43 4229 8192000000 10235255760 10657366016 1344975264 14.102 100 26 52 0 1357467504 6481696 6010544
23:28:56 13229 8192000000 10187262928 10600112128 1270512240 13.426 97 13 25 0 1279687536 6488368 2686928
23:30:10 40648 8192000000 10162041248 10565222400 1226925728 13.026 95 21 39 2 1236600080 4818688 4855664
23:31:52 4536 8192000000 10134562320 10526793728 1195132416 12.731 95 32 64 0 1208720208 6552992 7034800
23:32:57 5979 8192000000 10143908544 10540650496 1239507584 13.142 99 35 68 1 1253864064 6590496 7765984
23:34:04 13679 8192000000 10168118784 10574102528 1275036096 13.468 100 24 46 2 1285440992 5413888 4991008
23:35:18 28426 8192000000 10176084192 10588696576 1287491680 13.581 100 27 54 1 1299593920 6117376 5984864
23:36:47 7300 8192000000 10198843184 10614280192 1305025696 13.741 100 26 52 1 1320366560 9896352 5444512
23:37:56 8635 8192000000 10214061232 10624348160 1314097168 13.823 100 14 28 1 1322427712 5299248 3031296
23:39:09 58602 8192000000 10249679136 10665947136 1342717920 14.082 100 33 66 0 1356783360 6727712 7337728
23:41:11 61436 8192000000 10257117808 10688921600 1358282560 14.222 100 27 52 1 1377297056 12624256 6390240
23:43:14 24845 8192000000 10275214832 10703826944 1367006144 14.300 100 22 44 1 1389614480 17251536 5356800
23:44:42 13589 8192000000 10298145984 10720796672 1380815104 14.424 100 41 78 0 1409275744 19218640 9242000
23:46:01 27287 8192000000 10314641376 10737700864 1381478192 14.430 99 46 92 0 1414793200 22442784 10872224
23:47:32 6736 8192000000 10331369568 10755162112 1406534528 14.653 100 58 114 1 1445494768 24437872 14522368
23:48:42 3506 8192000000 10322766384 10744102912 1396210640 14.561 100 56 113 1 1435374064 25584816 13578608
23:49:52 4652 8192000000 10335574720 10759729152 1404300704 14.633 100 58 115 0 1447378720 28867344 14210672
23:51:00 6989 8192000000 10343290704 10769940480 1403718096 14.628 99 53 104 0 1447879680 30789984 13371600
23:52:10 2489 8192000000 10341710912 10756722688 1402069376 14.613 100 69 136 0 1447558544 29273200 16215968
23:53:16 2141 8192000000 10352961760 10769891328 1408431872 14.670 100 70 136 0 1459222080 33983920 16806288
23:54:21 2042 8192000000 10321707712 10724413440 1364760960 14.280 100 86 167 0 1423062432 37822480 20478992
23:55:28 1773 8192000000 10326344784 10726891520 1363237392 14.266 100 88 176 1 1429917008 44016640 22662976
23:56:34 1672 8192000000 10320419680 10723069952 1362232224 14.257 100 85 170 0 1421739440 38669328 20837888
23:57:41 7041 8192000000 10295802848 10726666240 1365077664 14.283 100 38 76 2 1408871776 33464048 10330064
23:58:58 19487 8192000000 10290230080 10725089280 1366342064 14.294 100 34 68 1 1401142064 25963664 8836336
00:01:05 11726 8192000000 10296967696 10730115072 1372535216 14.350 100 32 62 0 1409662000 28696240 8430544
00:02:21 16900 8192000000 10290288160 10714869760 1357938592 14.219 100 26 50 0 1393410464 28268272 7203600
00:03:56 15386 8192000000 10271877328 10713010176 1345475616 14.107 100 22 44 0 1386767712 34628176 6663920
00:05:14 28563 8192000000 10288507056 10730438656 1351106032 14.157 99 17 34 0 1388142128 32237040 4799056
00:06:45 27438 8192000000 10299891904 10741342208 1377177648 14.391 100 17 34 0 1407888656 26168320 4542688
00:08:23 104612 8192000000 10311100944 10750132224 1377901408 14.398 100 30 58 0 1413459904 28209152 7349344
00:11:09 78071 8192000000 10278949296 10724179968 1340114560 14.058 97 13 24 0 1356265616 14298128 1852928
00:13:27 89251 8192000000 10257269440 10716725248 1321417600 13.890 96 17 33 0 1336788304 11724752 3645952
00:16:01 84275 8192000000 10284628368 10740289536 1377047920 14.390 100 14 26 1 1391123216 11253568 2821728
00:18:30 79329 8192000000 10287397648 10730295296 1367508560 14.305 99 29 58 0 1380957824 6400448 7048816
00:20:56 102870 8192000000 10290909760 10755567616 1385240736 14.463 100 12 24 0 1402347232 14478592 2627904
00:23:39 39724 8192000000 10281181712 10728587264 1373582352 14.359 99 19 36 1 1385643536 8380080 3681104
00:25:23 29301 8192000000 10311218096 10752270336 1366251648 14.293 98 31 62 0 1385920368 12962976 6705744
00:27:00 42666 8192000000 10277547632 10747936768 1375738032 14.378 99 12 25 0 1386119552 7919248 2462272
00:28:44 38345 8192000000 10284745888 10746290176 1382223040 14.436 99 10 20 0 1392258512 7827232 2208240
00:30:23 43686 8192000000 10289097952 10764488704 1396566320 14.564 100 10 20 0 1408769440 9724464 2478656
00:32:40 16755 8192000000 10305896912 10766184448 1402638400 14.618 100 29 58 1 1423428816 13983984 6806432
00:33:59 73416 8192000000 10316482144 10780233728 1408914112 14.674 100 9 18 0 1421648192 10862560 1871520
00:36:13 40819 8192000000 10291764880 10761605120 1371893184 14.344 98 16 32 1 1385250480 9573984 3783312
00:37:56 44533 8192000000 10293147344 10745892864 1369927312 14.326 98 35 70 0 1386684352 9274688 7482352
00:39:40 34060 8192000000 10285453760 10738040832 1379320864 14.410 100 30 59 0 1392779136 6178640 7279632
00:41:18 48613 8192000000 10307383792 10759446528 1384523328 14.457 100 16 32 0 1408178416 20180448 3474640
00:43:07 113421 8192000000 10260975104 10715181056 1261171792 13.341 91 10 20 1 1283210144 19773392 2264960
00:46:01 56059 8192000000 10249787984 10700406784 1304523856 13.736 97 15 30 0 1321612848 13962720 3126272
00:47:58 101583 8192000000 10239101664 10667745280 1210377072 12.873 90 13 26 0 1228494144 15277600 2839472
00:51:08 59814 8192000000 10233015680 10681974784 1323876864 13.912 99 24 48 0 1338398592 8620912 5900816
00:53:24 52796 8192000000 10233779584 10688880640 1305919488 13.749 97 18 33 0 1322166016 12534880 3711648
00:55:45 46331 8192000000 10244264848 10705055744 1331755424 13.983 99 11 22 0 1349701760 15270864 2675472
00:57:31 68239 8192000000 10245734352 10708815872 1342282864 14.078 99 14 28 1 1356498848 9970528 4245456
00:59:54 60878 8192000000 10258971552 10709716992 1316564016 13.846 97 15 28 1 1327602736 7649664 3389056
01:02:00 46441 8192000000 10282468016 10727247872 1369166688 14.320 100 17 34 0 1389465648 16508784 3790176
01:03:52 31626 8192000000 10304909648 10753146880 1388090784 14.489 100 27 54 0 1407104160 12869584 6143792
01:05:29 90093 8192000000 10291109456 10746347520 1335146608 14.014 96 11 22 1 1352382000 14716960 2518432
01:08:00 47219 8192000000 10288690624 10743808000 1375665264 14.378 99 19 38 0 1389986496 10278704 4042528
01:09:49 60542 8192000000 10299760128 10761367552 1365856112 14.290 98 18 37 0 1381484496 11933392 3694992
01:11:54 58643 8192000000 10303659856 10766409728 1382985680 14.443 99 15 30 0 1399385296 13074672 3324944
01:14:01 74496 8192000000 10301213584 10779099136 1392591984 14.529 100 16 32 0 1416562208 20534896 3435328
01:16:16 61608 8192000000 10298190848 10748678144 1372351984 14.348 98 22 44 0 1384628064 7560544 4715536
01:18:20 21686 8192000000 10300429680 10758266880 1393943616 14.541 100 22 44 0 1412164640 13543200 4677824
01:19:42 45421 8192000000 10283377824 10749665280 1387503152 14.484 99 19 38 1 1403942336 11909056 4530128
01:21:40 67972 8192000000 10271171728 10725404672 1337436192 14.034 96 17 34 0 1349619840 8220704 3962944
01:23:48 64474 8192000000 10270558288 10721103872 1355579520 14.198 98 19 38 1 1368629952 8594416 4456016
01:26:23 70594 8192000000 10290458272 10745892864 1382480720 14.439 100 10 20 0 1394463568 9872272 2110576
01:29:13 73440 8192000000 10282591232 10734063616 1347267936 14.123 97 10 18 0 1362454400 12909600 2276864
01:31:26 43877 8192000000 10302230192 10757488640 1376559712 14.386 99 20 40 0 1392579120 10823856 5195552
01:33:36 49208 8192000000 10281775648 10743656448 1358989968 14.228 98 15 30 0 1379500944 16813504 3697472
01:35:33 37072 8192000000 10271684624 10708508672 1316700784 13.847 97 35 69 0 1341891920 17647328 7543808
01:37:11 46547 8192000000 10274010528 10722062336 1336552368 14.026 98 27 52 0 1355973664 13768240 5653056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-10-2022 01:39:31 to 07-10-2022 01:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-10-2022 01:39:47 to 07-10-2022 01:39:49.

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          238618
MON$OLDEST_ACTIVE               239278
MON$OLDEST_SNAPSHOT             239278
MON$NEXT_TRANSACTION            239278
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                       2023040
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:329: 
:330: mscs-test-161.budzdorov.ru    Fri Oct  7 01:38:39 2022
:331:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:332: 
:333: 
:334: mscs-test-161.budzdorov.ru    Fri Oct  7 01:38:39 2022
:335:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:336: 
--- end of diff output ---

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

07.10.22 01:39:50. 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-07 01:39:53 No severe PSQL-related problems occured
07.10.22 01:39:54. End of report.