Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.07.2022 22: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 16076992 kB
MemAvailable 31140388 kB
Buffers 0 kB
Cached 15659572 kB
SwapCached 2524 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-07-11 22:38:22
DTS_END                         2022-07-11 22:38:22.8100
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/42040
TRN_ID                          238833
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: 9321.35 21053 1118562
customer order: creation 167.62 350 20114
customer order: refuse 14.60 346 1752
order to supplier: creation 44.98 1289 5397
order to supplier: removal 3.35 111521 402
invoice (draft): creation 45.26 3398 5431
invoice (draft): removal 54.75 782 6570
invoice accept: apply 41.63 18312 4996
invoice accept: cancel 48.85 741 5862
customer reserve: creation 3845.08 180 461410
customer reserve: removal 4765.55 61 571866
realization accept: apply 81.09 190 9731
realization accept: cancel 80.18 116 9622
payment from customer: creation 58.91 109 7069
payment from customer: removal 5.84 85 701
payment to supplier: creation 48.34 118 5801
payment to supplier: removal 3.63 77 435
service: total inventory turnovers 4.50 1137 540
service: total monetary turnovers 6.38 27 765
service: aggregate perf. data 0.78 24027 94
service: refresh index statistics 0.03 258193 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-07-2022 22:40:15 to 11-07-2022 22:40:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7028
TEST_TIME 2 6764
TEST_TIME 3 7399
TEST_TIME 4 6293
TEST_TIME 5 6420
TEST_TIME 6 6435
TEST_TIME 7 6826
TEST_TIME 8 6552
TEST_TIME 9 6493
TEST_TIME 10 6502
TEST_TIME 11 6651
TEST_TIME 12 6599
TEST_TIME 13 6495
TEST_TIME 14 6486
TEST_TIME 15 6847
TEST_TIME 16 7037
TEST_TIME 17 6894
TEST_TIME 18 6778
TEST_TIME 19 7264
TEST_TIME 20 7439
TEST_TIME 21 7808
TEST_TIME 22 7997
TEST_TIME 23 8756
TEST_TIME 24 8996
TEST_TIME 25 9267
TEST_TIME 26 9530
TEST_TIME 27 9585
TEST_TIME 28 10374
TEST_TIME 29 11118
TEST_TIME 30 11215
TEST_TIME 31 11050
TEST_TIME 32 10992
TEST_TIME 33 10667
TEST_TIME 34 10625
TEST_TIME 35 10558
TEST_TIME 36 10385
TEST_TIME 37 10270
TEST_TIME 38 10303
TEST_TIME 39 10326
TEST_TIME 40 10250
TEST_TIME 41 10115
TEST_TIME 42 9993
TEST_TIME 43 10132
TEST_TIME 44 10054
TEST_TIME 45 9878
TEST_TIME 46 9784
TEST_TIME 47 9751
TEST_TIME 48 9614
TEST_TIME 49 9744
TEST_TIME 50 9635
TEST_TIME 51 9591
TEST_TIME 52 9509
TEST_TIME 53 9511
TEST_TIME 54 9391
TEST_TIME 55 9414
TEST_TIME 56 9330
TEST_TIME 57 9368
TEST_TIME 58 9353
TEST_TIME 59 9493
TEST_TIME 60 9492
TEST_TIME 61 9458
TEST_TIME 62 9515
TEST_TIME 63 9688
TEST_TIME 64 9631
TEST_TIME 65 9629
TEST_TIME 66 9647
TEST_TIME 67 9655
TEST_TIME 68 9753
TEST_TIME 69 9701
TEST_TIME 70 9691
TEST_TIME 71 9595
TEST_TIME 72 9523
TEST_TIME 73 9471
TEST_TIME 74 9511
TEST_TIME 75 9440
TEST_TIME 76 9394
TEST_TIME 77 9381
TEST_TIME 78 9317
TEST_TIME 79 9326
TEST_TIME 80 9254
TEST_TIME 81 9354
TEST_TIME 82 9317
TEST_TIME 83 9317
TEST_TIME 84 9341
TEST_TIME 85 9343
TEST_TIME 86 9422
TEST_TIME 87 9409
TEST_TIME 88 9434
TEST_TIME 89 9445
TEST_TIME 90 9451
TEST_TIME 91 9561
TEST_TIME 92 9560
TEST_TIME 93 9605
TEST_TIME 94 9505
TEST_TIME 95 9469
TEST_TIME 96 9481
TEST_TIME 97 9409
TEST_TIME 98 9380
TEST_TIME 99 9414
TEST_TIME 100 9409
TEST_TIME 101 9386
TEST_TIME 102 9360
TEST_TIME 103 9379
TEST_TIME 104 9422
TEST_TIME 105 9461
TEST_TIME 106 9421
TEST_TIME 107 9412
TEST_TIME 108 9493
TEST_TIME 109 9485
TEST_TIME 110 9506
TEST_TIME 111 9511
TEST_TIME 112 9528
TEST_TIME 113 9493
TEST_TIME 114 9447
TEST_TIME 115 9432
TEST_TIME 116 9449
TEST_TIME 117 9400
TEST_TIME 118 9423
TEST_TIME 119 9384
TEST_TIME 120 9326

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-07-2022 22:40:19 to 11-07-2022 22:40:22.

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 1546181 1546181 0 0.00 0 14779 6 0 0
doc_list_biud 1546179 1546179 0 0.00 0 43289 29 0 0
sp_add_invoice_to_stock 5162 4996 166 3.21 27 162808 18312 0 166
sp_cancel_adding_invoice 5862 5862 0 0.00 3 82902 741 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 12282 346 0 0
sp_cancel_customer_reserve 571866 571866 0 0.00 0 43292 62 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 7041 86 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 5106 77 0 0
sp_cancel_supplier_invoice 6581 6570 11 0.16 1 19822 782 0 11
sp_cancel_supplier_order 402 402 0 0.00 6 1026654 111522 0 0
sp_cancel_write_off 9622 9622 0 0.00 1 11048 116 0 0
sp_client_order 20114 20114 0 0.00 3 40411 351 0 0
sp_customer_reserve 461410 461410 0 0.00 0 75650 181 0 0
sp_fill_shopping_cart 30920 30920 0 0.00 1 10577 37 0 0
sp_fill_shopping_cart_clo_res 461397 461397 0 0.00 0 21572 13 0 0
sp_get_clo_for_invoice 4983 4983 0 0.00 0 22396 300 0 0
sp_kill_cost_storno 16620 16620 0 0.00 0 7529 56 0 0
sp_kill_qstorno_ret_qs2qd 1160326 1160326 0 0.00 0 41143 33 0 0
sp_kill_qty_storno 610788 610788 0 0.00 0 43288 71 0 0
sp_lock_selected_doc 20440 20440 0 0.00 0 7471 6 0 0
sp_make_cost_storno 27584 27584 0 0.00 0 14739 48 0 0
sp_multiply_rows_for_pdistr 27584 27584 0 0.00 0 7530 27 0 0
sp_multiply_rows_for_qdistr 20114 20114 0 0.00 0 40264 143 0 0
sp_pay_from_customer 7069 7069 0 0.00 4 15851 110 0 0
sp_pay_to_supplier 5801 5801 0 0.00 4 12862 118 0 0
sp_payment_common 12870 12870 0 0.00 1 8907 26 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 11749 314 0 0
sp_qd_handle_on_invoice_upd_sts 10845 10845 0 0.00 0 40479 323 0 0
sp_qd_handle_on_reserve_upd_sts 9731 9731 0 0.00 0 17822 65 0 0
sp_reserve_write_off 9731 9731 0 0.00 5 17855 190 0 0
sp_supplier_invoice 5431 5431 0 0.00 6 87433 3399 0 0
sp_supplier_order 5397 5397 0 0.00 4 68700 1290 0 0
srv_aggregate_perf_data 94 94 0 0.00 2537 487375 24027 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1805 116857 43299 0 0
srv_find_qd_qs_mism 1071493 1071493 0 0.00 0 11669 5 0 0
srv_make_invnt_saldo 540 540 0 0.00 30 73680 1137 0 0
srv_make_money_saldo 765 765 0 0.00 1 789 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 138346 386810 258194 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4996 4996 0 0.00 0 24906 189 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 6867 115 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 2026 16 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 2647 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 11296 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 621 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 1169 17 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 30191 1662 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 2082 6 0 0
t$perf-norm:sp_client_order 20114 20114 0 0.00 0 7625 16 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 4438 14 0 0
t$perf-norm:sp_pay_from_customer 7069 7069 0 0.00 0 6560 12 0 0
t$perf-norm:sp_pay_to_supplier 5801 5801 0 0.00 0 6266 12 0 0
t$perf-norm:sp_reserve_write_off 9731 9731 0 0.00 0 11453 13 0 0
t$perf-norm:sp_supplier_invoice 5431 5431 0 0.00 0 10203 17 0 0
t$perf-norm:sp_supplier_order 5397 5397 0 0.00 0 4692 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 299 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 2150 25 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 1762 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10296 9964 332 3.22 0 12995 26 0 332
v_all_customers 36810 36810 0 0.00 0 125 1 0 0
v_all_suppliers 29888 29888 0 0.00 0 23 1 0 0
v_all_wares 40228 40228 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 2203 9 0 0
v_cancel_client_order 3504 3504 0 0.00 0 12103 10 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 3231 8 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 921 6 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 4107 9 0 0
v_cancel_supplier_invoice 824 802 22 2.66 0 2195 20 0 22
v_cancel_supplier_order 804 804 0 0.00 0 1938 12 0 0
v_cancel_write_off 2032 2032 0 0.00 0 10418 28 0 0
v_max_id_clo_ord 5397 5397 0 0.00 0 3033 3 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 2801 14 0 0
v_max_id_ord_sup 5409 5409 0 0.00 0 4408 3 0 0
v_max_non_paid_invoice 1663 1663 0 0.00 0 147 2 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 6248 5 0 0
v_min_id_clo_ord 5397 5397 0 0.00 0 4032 6 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 1037 2 0 0
v_min_id_ord_sup 5409 5409 0 0.00 0 3604 5 0 0
v_min_non_paid_invoice 5801 5801 0 0.00 0 7106 17 0 0
v_min_non_paid_realizn 7069 7069 0 0.00 0 7007 20 0 0
v_our_firm 9900 9900 0 0.00 0 21 1 0 0
v_reserve_write_off 19462 19462 0 0.00 0 15803 11 0 0
x_lock_depdocs_on_canc_invoice 12031 12031 0 0.00 0 13327 115 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 12487 346 0 0
x_make_qstorno_1000_1200 5397 5397 0 0.00 1 68679 1214 0 0
x_make_qstorno_1200_2000 5431 5431 0 0.00 2 87426 3260 0 0
x_make_qstorno_2100_3300 461410 461410 0 0.00 0 75650 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-07-2022 22:40:23 to 11-07-2022 22:40:27.

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:26 7 8192000000 8798879696 8810233856 5307904 0.064 2 1 2 0 5407504 56816 42784
20:09:27 20572 8192000000 9897650096 10205814784 976671504 10.652 100 85 170 0 1004562432 5464848 22426080
20:10:57 17422 8192000000 9938417920 10257145856 1018350608 11.056 100 87 174 0 1049065280 8026800 22687872
20:12:18 7638 8192000000 9972003008 10300379136 1055552144 11.414 100 88 176 0 1087983808 9920544 22511120
20:13:27 10646 8192000000 10018180832 10366017536 1110781664 11.940 100 79 158 1 1139898848 10172128 18945056
20:14:50 3810 8192000000 10090378112 10448166912 1172245040 12.518 100 81 162 1 1203341776 12541824 18554912
20:16:00 2938 8192000000 10124793840 10499297280 1218931264 12.952 100 77 154 2 1248548736 12908080 16709392
20:17:04 2076 8192000000 10169224832 10547601408 1254669968 13.281 100 90 178 1 1288149920 13567248 19912704
20:18:09 1162 8192000000 10192637568 10572845056 1286346288 13.571 100 87 170 0 1317668128 12569872 18751968
20:19:11 20657 8192000000 10193845888 10588495872 1292130416 13.624 100 64 127 1 1316693488 9429376 15133696
20:20:32 4892 8192000000 10187863600 10597507072 1300268384 13.698 100 30 58 1 1314166928 6579232 7319312
20:21:38 6130 8192000000 10194366560 10608226304 1310927488 13.794 100 25 50 0 1323012352 6302560 5782304
20:22:45 8812 8192000000 10199243392 10615484416 1316845024 13.848 100 18 37 0 1326625200 5445200 4334976
20:23:55 56354 8192000000 10227086704 10633658368 1321065648 13.886 100 46 92 1 1337346832 6174336 10106848
20:25:52 3552 8192000000 10214067152 10627833856 1308330576 13.771 99 31 62 0 1322088928 7038160 6720192
20:26:57 7074 8192000000 10220366992 10637332480 1326381584 13.934 100 25 50 0 1338763760 6073840 6308336
20:28:05 53959 8192000000 10157806736 10555592704 1205887024 12.831 92 32 64 1 1219151056 4895952 8368080
20:30:03 6419 8192000000 10152833840 10551963648 1234854992 13.099 98 29 58 0 1248389232 6760576 6773664
20:31:14 8971 8192000000 10133585872 10529533952 1209654768 12.866 96 20 38 0 1218951056 4728832 4567456
20:32:26 73053 8192000000 10146286144 10551570432 1225055264 13.008 97 35 71 0 1239447536 5128512 9263760
20:34:41 6230 8192000000 10169216608 10579525632 1278104336 13.496 100 22 44 0 1289640736 5938480 5597920
20:35:50 8691 8192000000 10184206752 10592137216 1287430416 13.581 100 20 39 0 1297822528 5301040 5091072
20:36:59 10588 8192000000 10186634640 10603450368 1298676304 13.683 100 14 29 0 1307245152 5401616 3167232
20:38:13 47066 8192000000 10219142192 10629361664 1316099440 13.841 100 42 82 1 1330567680 4842400 9625840
20:40:12 15140 8192000000 10253366992 10673659904 1351247600 14.159 100 41 82 1 1368890256 9111456 8531200
20:41:29 3700 8192000000 10274836672 10696466432 1370031216 14.327 100 42 84 0 1387903968 7723104 10149648
20:42:39 26987 8192000000 10297590016 10722652160 1382365264 14.438 100 44 86 1 1403741248 12067568 9308416
20:44:08 10174 8192000000 10306904112 10730053632 1392828928 14.531 100 58 115 0 1428535216 22743680 12962608
20:45:22 20701 8192000000 10296676576 10728951808 1380597664 14.422 99 37 74 1 1410975920 21591008 8787248
20:46:53 2710 8192000000 10308610464 10727665664 1369663024 14.324 98 55 110 0 1403692320 21668672 12360624
20:47:58 7199 8192000000 10320764848 10738794496 1393740016 14.539 100 61 117 1 1433894032 26034880 14119136
20:49:12 19483 8192000000 10328890160 10747129856 1394525088 14.546 100 57 114 0 1439129936 30763264 13841584
20:50:37 10722 8192000000 10321920320 10741514240 1393547856 14.538 100 48 95 0 1432236672 27090032 11598784
20:51:53 3725 8192000000 10323678960 10737557504 1389701296 14.503 100 54 105 0 1437032368 34185104 13145968
20:53:01 4161 8192000000 10321051792 10724564992 1369442368 14.322 99 68 136 1 1426719696 39678656 17598672
20:54:14 2829 8192000000 10342155296 10750332928 1383983072 14.452 100 77 153 1 1444943744 41485744 19474928
20:55:23 7162 8192000000 10341714912 10746523648 1360504384 14.242 98 66 132 0 1424040128 47116656 16419088
20:56:39 2683 8192000000 10331032848 10728185856 1354715824 14.190 99 80 151 2 1428706448 53690208 20300416
20:57:47 4334 8192000000 10330522144 10725175296 1363523968 14.269 100 80 154 3 1432163504 48555856 20083680
20:59:02 1801 8192000000 10361585840 10759974912 1381682864 14.432 100 89 178 1 1462114528 57088720 23342944
21:00:12 3403 8192000000 10356014240 10759966720 1384147024 14.454 100 71 140 1 1456134272 53537648 18449600
21:01:18 7053 8192000000 10340765568 10757345280 1371593008 14.341 100 52 104 0 1445944688 59649440 14702240
21:02:29 13878 8192000000 10332850816 10751819776 1371600832 14.341 99 45 90 0 1426716176 43121648 11993696
21:03:46 17294 8192000000 10321256848 10752184320 1374439120 14.367 100 27 54 0 1429800864 47378816 7982928
21:05:20 31076 8192000000 10320576432 10759032832 1360315520 14.240 100 25 49 1 1421834752 54214224 7305008
21:07:07 43322 8192000000 10262557808 10730487808 1335828384 14.020 99 9 18 1 1368626048 30399120 2398544
21:09:03 48412 8192000000 10280937136 10712371200 1321615840 13.891 97 20 41 1 1339033344 13065168 4352336
21:10:51 76258 8192000000 10269942944 10710487040 1337950944 14.039 98 37 74 0 1356265920 9444768 8870208
21:13:16 47338 8192000000 10279846016 10735951872 1371381552 14.339 100 14 28 0 1387010512 11800336 3828624
21:16:03 60441 8192000000 10284428672 10746810368 1376710016 14.387 100 17 34 1 1401292336 20814592 3767728
21:18:04 62958 8192000000 10285141200 10745434112 1374378256 14.366 99 18 36 0 1389324816 10895744 4050816
21:20:17 67189 8192000000 10298465552 10758111232 1375536896 14.377 99 26 52 0 1394267920 12795552 5935472
21:22:49 38927 8192000000 10302908208 10764251136 1400402672 14.599 100 7 14 0 1412449152 10566496 1479984
21:25:49 31400 8192000000 10308739744 10781425664 1407779680 14.664 100 9 18 0 1425102736 15098448 2224608
21:27:22 32665 8192000000 10300542912 10771435520 1405793552 14.647 100 14 29 0 1416646944 7504624 3348768
21:28:55 92722 8192000000 10318914272 10779131904 1416717952 14.744 100 17 34 0 1427791008 7633200 3439856
21:31:30 54717 8192000000 10305222848 10775015424 1383928080 14.452 98 12 24 0 1393978976 7473360 2577536
21:33:30 81497 8192000000 10313027040 10782593024 1396854032 14.567 99 11 21 0 1409884080 11237280 1792768
21:35:52 72642 8192000000 10305218384 10767147008 1364887616 14.281 96 22 44 0 1382228032 12647584 4692832
21:38:05 25650 8192000000 10313460016 10760097792 1378181776 14.400 98 27 53 0 1392287648 7596272 6509600
21:39:32 33473 8192000000 10296058352 10747752448 1351811152 14.164 96 22 44 1 1366749776 10261488 4677136
21:41:05 108447 8192000000 10291461808 10742308864 1288141376 13.587 92 12 24 0 1312825200 21932336 2751488
21:44:00 10541 8192000000 10307081760 10748641280 1376270800 14.383 99 35 70 0 1393995168 9823424 7900944
21:45:16 43339 8192000000 10288865936 10733064192 1326509696 13.936 96 19 38 0 1342358736 11827728 4021312
21:47:05 42419 8192000000 10275339520 10715660288 1317306368 13.852 96 29 59 0 1332585408 9191264 6087776
21:49:47 44372 8192000000 10260171792 10705424384 1329935168 13.967 98 19 38 0 1341078048 7410864 3732016
21:51:33 39792 8192000000 10253013984 10703794176 1314020656 13.823 96 31 62 2 1335939472 15303376 6615440
21:53:20 72187 8192000000 10248079664 10707714048 1333189984 13.996 98 9 18 0 1343284832 8363296 1731552
21:55:33 57319 8192000000 10252795456 10703437824 1338057472 14.040 99 19 39 0 1349847408 7395664 4394272
21:57:31 64268 8192000000 10248309744 10712825856 1331335776 13.979 98 12 24 0 1343880080 9788368 2755936
21:59:37 79458 8192000000 10270951024 10715987968 1344992272 14.102 99 9 18 0 1359688384 12728624 1967488
22:02:17 32335 8192000000 10267576256 10705362944 1312172384 13.806 96 40 81 0 1332148352 10948736 9027232
22:03:51 72219 8192000000 10264084736 10719236096 1310846912 13.794 96 19 36 0 1329128032 14150672 4130448
22:06:10 116835 8192000000 10288940496 10734354432 1356518336 14.206 99 23 46 0 1374557536 12605792 5433408
22:09:16 83142 8192000000 10296998576 10754134016 1369534832 14.323 99 11 22 0 1384256192 12761472 1959888
22:11:41 101010 8192000000 10304850016 10759225344 1383177376 14.445 99 26 52 1 1397163632 7983760 6002496
22:14:31 71467 8192000000 10290278608 10762108928 1362342928 14.258 97 6 12 0 1380917600 17302224 1272448
22:16:57 72362 8192000000 10297361184 10772697088 1390792768 14.513 100 18 36 0 1408099008 12436304 4869936
22:19:23 42143 8192000000 10296327504 10761347072 1387551984 14.484 99 11 23 0 1398372208 8376240 2443984
22:21:20 88782 8192000000 10321491536 10798297088 1395029200 14.551 99 10 20 0 1413054176 15542368 2482608
22:24:03 35987 8192000000 10298821280 10753789952 1324678592 13.919 94 14 28 0 1334003104 6510784 2813728
22:25:42 93774 8192000000 10291644928 10755088384 1350934992 14.156 97 24 48 0 1368118432 11965392 5218048
22:28:23 76210 8192000000 10272991056 10743345152 1298107840 13.678 94 12 24 0 1316150560 15682320 2360400
22:30:41 46342 8192000000 10292865152 10751725568 1377308352 14.392 100 20 40 0 1395394944 13881440 4205152
22:32:28 82898 8192000000 10289268672 10750914560 1369415632 14.322 98 25 50 0 1387152144 12160976 5575536
22:34:52 49946 8192000000 10266474416 10723336192 1330522352 13.972 97 23 46 0 1349744992 13400368 5822272
22:36:42 37240 8192000000 10257202144 10721304576 1361333040 14.249 100 10 20 0 1372917264 9459856 2124368
22:38:19 16640 8192000000 9183219744 9449549824 346632672 4.059 26 1 2 0 352711424 6036400 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-07-2022 22:40:29 to 11-07-2022 22:40:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-07-2022 22:40:45 to 11-07-2022 22:40:47.

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          239731
MON$OLDEST_ACTIVE               239732
MON$OLDEST_SNAPSHOT             239732
MON$NEXT_TRANSACTION            239732
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                       2039648
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:924: 
:925: mscs-test-161.budzdorov.ru    Mon Jul 11 22:38:38 2022
:926:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:927: 
--- end of diff output ---

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

11.07.22 22:40:48. 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-07-11 22:40:50 No severe PSQL-related problems occured
11.07.22 22:40:52. End of report.