Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.05.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 16308876 kB
MemAvailable 31462128 kB
Buffers 0 kB
Cached 15427928 kB
SwapCached 2632 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.10
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-05-17 22:38:12
DTS_END                         2022-05-17 22:38:12.0810
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/49480
TRN_ID                          245137
ATT_ID                          641
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: 9373.28 21571 1124793
customer order: creation 176.83 375 21220
customer order: refuse 15.38 354 1845
order to supplier: creation 47.29 1286 5675
order to supplier: removal 3.39 105677 407
invoice (draft): creation 47.91 3492 5749
invoice (draft): removal 53.15 818 6378
invoice accept: apply 43.13 19034 5176
invoice accept: cancel 48.07 786 5768
customer reserve: creation 3917.60 191 470112
customer reserve: removal 4710.25 59 565230
realization accept: apply 85.98 180 10318
realization accept: cancel 90.62 101 10874
payment from customer: creation 61.06 130 7327
payment from customer: removal 5.57 84 668
payment to supplier: creation 50.50 129 6060
payment to supplier: removal 3.49 62 419
service: total inventory turnovers 4.97 1073 596
service: total monetary turnovers 7.28 35 873
service: aggregate perf. data 0.78 24823 94
service: refresh index statistics 0.03 272728 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-05-2022 22:40:21 to 17-05-2022 22:40:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3230
TEST_TIME 2 3208
TEST_TIME 3 4797
TEST_TIME 4 4788
TEST_TIME 5 5510
TEST_TIME 6 6057
TEST_TIME 7 6440
TEST_TIME 8 6250
TEST_TIME 9 6079
TEST_TIME 10 6326
TEST_TIME 11 6491
TEST_TIME 12 6602
TEST_TIME 13 6993
TEST_TIME 14 7063
TEST_TIME 15 7150
TEST_TIME 16 7182
TEST_TIME 17 7468
TEST_TIME 18 7340
TEST_TIME 19 7728
TEST_TIME 20 8127
TEST_TIME 21 8104
TEST_TIME 22 8183
TEST_TIME 23 8226
TEST_TIME 24 8500
TEST_TIME 25 8786
TEST_TIME 26 9286
TEST_TIME 27 9420
TEST_TIME 28 9305
TEST_TIME 29 9888
TEST_TIME 30 10121
TEST_TIME 31 10586
TEST_TIME 32 10484
TEST_TIME 33 10542
TEST_TIME 34 10430
TEST_TIME 35 10349
TEST_TIME 36 10178
TEST_TIME 37 10085
TEST_TIME 38 9940
TEST_TIME 39 9993
TEST_TIME 40 9977
TEST_TIME 41 9798
TEST_TIME 42 9770
TEST_TIME 43 9709
TEST_TIME 44 9610
TEST_TIME 45 9513
TEST_TIME 46 9606
TEST_TIME 47 9741
TEST_TIME 48 9630
TEST_TIME 49 9613
TEST_TIME 50 9578
TEST_TIME 51 9674
TEST_TIME 52 9834
TEST_TIME 53 9763
TEST_TIME 54 9788
TEST_TIME 55 9698
TEST_TIME 56 9792
TEST_TIME 57 9772
TEST_TIME 58 9904
TEST_TIME 59 9869
TEST_TIME 60 9865
TEST_TIME 61 9821
TEST_TIME 62 9889
TEST_TIME 63 9903
TEST_TIME 64 9893
TEST_TIME 65 9807
TEST_TIME 66 9708
TEST_TIME 67 9764
TEST_TIME 68 9750
TEST_TIME 69 9680
TEST_TIME 70 9639
TEST_TIME 71 9582
TEST_TIME 72 9710
TEST_TIME 73 9689
TEST_TIME 74 9618
TEST_TIME 75 9608
TEST_TIME 76 9511
TEST_TIME 77 9442
TEST_TIME 78 9391
TEST_TIME 79 9350
TEST_TIME 80 9405
TEST_TIME 81 9388
TEST_TIME 82 9384
TEST_TIME 83 9390
TEST_TIME 84 9404
TEST_TIME 85 9342
TEST_TIME 86 9403
TEST_TIME 87 9384
TEST_TIME 88 9458
TEST_TIME 89 9494
TEST_TIME 90 9502
TEST_TIME 91 9559
TEST_TIME 92 9612
TEST_TIME 93 9598
TEST_TIME 94 9649
TEST_TIME 95 9709
TEST_TIME 96 9645
TEST_TIME 97 9602
TEST_TIME 98 9589
TEST_TIME 99 9529
TEST_TIME 100 9492
TEST_TIME 101 9459
TEST_TIME 102 9421
TEST_TIME 103 9467
TEST_TIME 104 9421
TEST_TIME 105 9417
TEST_TIME 106 9491
TEST_TIME 107 9469
TEST_TIME 108 9485
TEST_TIME 109 9433
TEST_TIME 110 9432
TEST_TIME 111 9580
TEST_TIME 112 9540
TEST_TIME 113 9524
TEST_TIME 114 9572
TEST_TIME 115 9552
TEST_TIME 116 9487
TEST_TIME 117 9480
TEST_TIME 118 9437
TEST_TIME 119 9437
TEST_TIME 120 9374

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-05-2022 22:40:24 to 17-05-2022 22:40:28.

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 1561400 1561400 0 0.00 0 16272 6 0 0
doc_list_biud 1561371 1561371 0 0.00 0 28095 27 0 0
sp_add_invoice_to_stock 5252 5176 76 1.44 34 192327 19034 0 76
sp_cancel_adding_invoice 5768 5768 0 0.00 4 63378 787 0 0
sp_cancel_client_order 1845 1845 0 0.00 4 13121 355 0 0
sp_cancel_customer_reserve 565230 565230 0 0.00 1 27335 60 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 7751 84 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 2 5596 63 0 0
sp_cancel_supplier_invoice 6383 6378 5 0.07 2 28106 819 0 5
sp_cancel_supplier_order 407 407 0 0.00 4 975425 105678 0 0
sp_cancel_write_off 10874 10874 0 0.00 1 8155 102 0 0
sp_client_order 21220 21220 0 0.00 3 26619 375 0 0
sp_customer_reserve 470112 470112 0 0.00 0 43855 191 0 0
sp_fill_shopping_cart 32632 32632 0 0.00 1 17274 38 0 0
sp_fill_shopping_cart_clo_res 470110 470110 0 0.00 0 16332 13 0 0
sp_get_clo_for_invoice 5174 5174 0 0.00 0 36168 335 0 0
sp_kill_cost_storno 17729 17729 0 0.00 0 8145 53 0 0
sp_kill_qstorno_ret_qs2qd 1148119 1148119 0 0.00 0 28090 32 0 0
sp_kill_qty_storno 605979 605979 0 0.00 0 28093 70 0 0
sp_lock_selected_doc 21405 21405 0 0.00 0 9119 6 0 0
sp_make_cost_storno 28879 28879 0 0.00 0 16182 54 0 0
sp_multiply_rows_for_pdistr 28850 28850 0 0.00 0 8476 32 0 0
sp_multiply_rows_for_qdistr 21220 21220 0 0.00 0 26610 154 0 0
sp_pay_from_customer 7327 7327 0 0.00 4 15730 130 0 0
sp_pay_to_supplier 6060 6060 0 0.00 5 14673 129 0 0
sp_payment_common 13373 13373 0 0.00 1 14893 23 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 13113 320 0 0
sp_qd_handle_on_invoice_upd_sts 10937 10937 0 0.00 0 25019 313 0 0
sp_qd_handle_on_reserve_upd_sts 10308 10308 0 0.00 0 20214 60 0 0
sp_reserve_write_off 10318 10318 0 0.00 6 20317 181 0 0
sp_supplier_invoice 5749 5749 0 0.00 5 90706 3492 0 0
sp_supplier_order 5675 5675 0 0.00 5 32329 1287 0 0
srv_aggregate_perf_data 94 94 0 0.00 2253 396479 24823 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1256 124813 44719 0 0
srv_find_qd_qs_mism 1076449 1076449 0 0.00 0 10164 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 29 59276 1073 0 0
srv_make_money_saldo 873 873 0 0.00 0 10397 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 206796 357045 272729 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5176 5176 0 0.00 0 13225 182 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 2588 92 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 6399 13 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 2311 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 5600 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 867 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 4378 42 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 22288 1545 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 2281 9 0 0
t$perf-norm:sp_client_order 21220 21220 0 0.00 0 10346 18 0 0
t$perf-norm:sp_customer_reserve 1060 1060 0 0.00 0 4496 14 0 0
t$perf-norm:sp_pay_from_customer 7327 7327 0 0.00 0 11061 13 0 0
t$perf-norm:sp_pay_to_supplier 6060 6060 0 0.00 0 4678 12 0 0
t$perf-norm:sp_reserve_write_off 10318 10318 0 0.00 0 7450 12 0 0
t$perf-norm:sp_supplier_invoice 5749 5749 0 0.00 0 5498 19 0 0
t$perf-norm:sp_supplier_order 5675 5675 0 0.00 0 4213 18 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 264 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 126 3 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 3723 30 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 2278 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10488 10336 152 1.44 0 7832 19 0 152
v_all_customers 38522 38522 0 0.00 0 22 1 0 0
v_all_suppliers 31296 31296 0 0.00 0 17 1 0 0
v_all_wares 42440 42440 0 0.00 0 144 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 425 7 0 0
v_cancel_client_order 3690 3690 0 0.00 0 3511 5 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 7727 11 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 6289 12 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 5582 10 0 0
v_cancel_supplier_invoice 792 782 10 1.26 0 381 12 0 10
v_cancel_supplier_order 814 814 0 0.00 0 544 4 0 0
v_cancel_write_off 2088 2088 0 0.00 0 6308 17 0 0
v_max_id_clo_ord 5669 5669 0 0.00 0 258 1 0 0
v_max_id_clo_res 1060 1060 0 0.00 0 4143 19 0 0
v_max_id_ord_sup 5743 5743 0 0.00 0 2411 2 0 0
v_max_non_paid_invoice 1815 1815 0 0.00 0 211 2 0 0
v_max_non_paid_realizn 3932 3932 0 0.00 0 6050 6 0 0
v_min_id_clo_ord 5669 5669 0 0.00 0 4250 8 0 0
v_min_id_clo_res 1060 1060 0 0.00 0 5994 13 0 0
v_min_id_ord_sup 5743 5743 0 0.00 0 7919 9 0 0
v_min_non_paid_invoice 6052 6052 0 0.00 0 7880 11 0 0
v_min_non_paid_realizn 7320 7320 0 0.00 0 14392 18 0 0
v_our_firm 10694 10694 0 0.00 0 141 1 0 0
v_reserve_write_off 20616 20616 0 0.00 0 7841 8 0 0
x_lock_depdocs_on_canc_invoice 11755 11755 0 0.00 0 26422 124 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 7331 350 0 0
x_make_qstorno_1000_1200 5675 5675 0 0.00 1 32324 1206 0 0
x_make_qstorno_1200_2000 5749 5749 0 0.00 2 82790 3361 0 0
x_make_qstorno_2100_3300 470112 470112 0 0.00 0 43854 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-05-2022 22:40:29 to 17-05-2022 22:40:33.

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:15 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:17 27965 8192000000 9899295856 10209902592 984813616 10.731 100 91 182 0 1014976752 6309552 23853584
20:10:53 24783 8192000000 9953721392 10274119680 1031485088 11.183 100 92 185 1 1063612304 8661440 23465776
20:12:21 14812 8192000000 9985974016 10321858560 1073207296 11.583 100 78 156 0 1102689184 10145312 19336576
20:13:46 12157 8192000000 10040603520 10388107264 1122751600 12.053 100 75 150 3 1151135680 10675488 17708592
20:15:04 9010 8192000000 10087580000 10448113664 1175164464 12.545 100 83 166 0 1207166352 12734304 19267584
20:16:15 5608 8192000000 10140911456 10512330752 1226864704 13.025 100 82 162 1 1257628864 12016704 18747456
20:17:22 2364 8192000000 10174935648 10548436992 1254611024 13.281 100 85 170 0 1288630912 15616016 18403872
20:18:28 1524 8192000000 10203792128 10584657920 1284277008 13.552 100 88 177 0 1317754752 14405104 19072640
20:19:30 55607 8192000000 10208754432 10607476736 1303148912 13.724 100 60 118 1 1325183376 7987888 14046576
20:21:26 10143 8192000000 10198635056 10610941952 1304217216 13.734 100 22 43 1 1315457408 6084320 5155872
20:22:44 13989 8192000000 10208932240 10625490944 1318702080 13.865 100 15 30 2 1327833264 5433216 3697968
20:23:59 13036 8192000000 10212649968 10630946816 1325406096 13.926 100 18 36 1 1335493296 5923408 4163792
20:25:12 45513 8192000000 10239391264 10641428480 1315829200 13.839 99 47 93 1 1332303824 4515264 11959360
20:26:58 4596 8192000000 10227351904 10645823488 1325495648 13.926 99 29 58 1 1338202752 6264432 6442672
20:28:04 6524 8192000000 10202225696 10621837312 1294266288 13.643 98 16 32 0 1307825664 9977936 3581440
20:29:11 40626 8192000000 10141194656 10546982912 1157177920 12.377 89 16 31 0 1165288256 4627200 3483136
20:30:52 5477 8192000000 10144473008 10543476736 1215191344 12.917 96 25 50 0 1226282528 5240464 5850720
20:31:58 6711 8192000000 10134244224 10528403456 1229680528 13.051 99 44 88 2 1247109344 6642800 10786016
20:33:09 59754 8192000000 10173280720 10568208384 1268194000 13.405 100 38 76 1 1282805312 5404976 9206336
20:35:17 5395 8192000000 10170285520 10579898368 1277955760 13.494 100 22 44 1 1288527136 5090976 5480400
20:36:28 8108 8192000000 10182825632 10596503552 1292316608 13.625 100 15 30 0 1301783744 5825744 3641392
20:37:38 13938 8192000000 10191718608 10608377856 1304447808 13.736 100 23 46 1 1315655376 5603936 5603632
20:38:55 42731 8192000000 10226867440 10639106048 1314790096 13.830 99 33 66 0 1328171696 6159248 7222352
20:40:42 35630 8192000000 10257685584 10676858880 1345487936 14.107 100 48 96 2 1367309360 10799088 11022336
20:42:20 5079 8192000000 10279536176 10694180864 1367793872 14.307 100 57 113 1 1392035248 11055776 13185600
20:43:28 15397 8192000000 10286148544 10709696512 1373488032 14.358 100 41 81 2 1395650544 12537632 9624880
20:44:48 5619 8192000000 10293389024 10713190400 1380555088 14.422 100 63 124 0 1410821584 15125408 15141088
20:45:59 16324 8192000000 10295031904 10717679616 1380140992 14.418 100 43 86 1 1407203584 17467792 9594800
20:47:18 11836 8192000000 10320399872 10741174272 1396280448 14.562 100 61 122 1 1426023184 16536256 13206480
20:48:40 8074 8192000000 10338192400 10759512064 1409319840 14.678 100 68 134 3 1445556448 20684256 15552352
20:49:53 1922 8192000000 10339294320 10756034560 1394858448 14.549 99 79 158 1 1438674784 24114176 19702160
20:51:00 2928 8192000000 10331736080 10750025728 1384253824 14.455 99 74 148 2 1430460976 28532800 17674352
20:52:08 3289 8192000000 10308829792 10719887360 1370821792 14.334 99 67 132 0 1413748000 27348192 15578016
20:53:20 7656 8192000000 10308941216 10725339136 1369770240 14.325 100 64 126 2 1413565536 28672112 15123184
20:54:34 4246 8192000000 10336117232 10744807424 1386178496 14.472 100 77 153 1 1437569968 32890096 18501376
20:55:44 1878 8192000000 10322072832 10733002752 1376170416 14.382 100 70 139 1 1425759136 32249776 17338944
20:56:50 3085 8192000000 10315729600 10720526336 1349934240 14.147 99 83 165 1 1408509840 37842192 20733408
20:57:58 1882 8192000000 10344235536 10750124032 1372989824 14.354 99 92 184 3 1433482304 37857520 22634960
20:59:04 9006 8192000000 10317379040 10732568576 1366950032 14.300 99 50 100 1 1416826640 36729952 13146656
21:00:19 1255 8192000000 10345475984 10750210048 1386351872 14.473 100 80 158 0 1447993200 42279952 19361376
21:01:25 3814 8192000000 10348886032 10761236480 1384875824 14.460 100 65 128 0 1448164832 45883376 17405632
21:02:36 2614 8192000000 10342195632 10748854272 1368100224 14.310 99 73 147 2 1439252128 50847248 20304656
21:03:42 28303 8192000000 10326134288 10756096000 1365860448 14.290 99 33 66 1 1420912992 45438400 9614144
21:05:22 10891 8192000000 10314271616 10743320576 1364473232 14.277 99 26 52 0 1409915168 38036000 7405936
21:06:41 27543 8192000000 10302736096 10739552256 1361817504 14.254 100 33 65 1 1414988624 44134192 9036928
21:08:11 29839 8192000000 10279245712 10720903168 1341821488 14.074 99 18 36 0 1373795296 27656016 4317792
21:09:42 44186 8192000000 10264369024 10706755584 1359974912 14.237 100 9 18 0 1376539120 15072192 1492016
21:11:28 83795 8192000000 10297197792 10746339328 1375160048 14.373 99 12 24 1 1386852992 9411120 2281824
21:13:57 21334 8192000000 10318775104 10739249152 1380616512 14.422 100 48 96 0 1399482048 6807280 12058256
21:15:21 85763 8192000000 10300386016 10748559360 1384385488 14.456 100 29 57 2 1399073472 7914672 6773312
21:17:54 92214 8192000000 10302683584 10747027456 1353558032 14.179 97 24 46 1 1372344192 13432320 5353840
21:20:28 10499 8192000000 10311331072 10754846720 1392950064 14.532 100 40 80 0 1417018272 14760416 9307792
21:21:40 31432 8192000000 10310788000 10766974976 1401652544 14.610 100 23 46 2 1422107776 14734624 5720608
21:23:12 32644 8192000000 10329969936 10775973888 1397614464 14.574 99 47 94 1 1428795008 19499248 11681296
21:24:59 68279 8192000000 10311993968 10788057088 1404165024 14.632 100 15 30 2 1430248832 22021328 4062480
21:27:12 52783 8192000000 10303311568 10771787776 1388308944 14.491 99 15 31 2 1408395408 16038480 4047984
21:29:12 51289 8192000000 10316126384 10782285824 1399717376 14.592 100 16 32 2 1422191456 18590784 3883296
21:31:13 65231 8192000000 10307321792 10765262848 1399378576 14.589 100 30 60 1 1417815232 11172944 7263712
21:33:35 36413 8192000000 10315603360 10760503296 1373761984 14.361 98 26 52 0 1393324832 13675712 5887136
21:35:16 62231 8192000000 10295280096 10748567552 1336240800 14.024 96 23 46 2 1357277584 15129328 5907456
21:37:25 44775 8192000000 10272275568 10713260032 1313783024 13.820 95 39 78 1 1330889120 7995952 9110144
21:39:11 46310 8192000000 10287454000 10726211584 1327111520 13.941 96 24 48 1 1339526080 7371936 5042624
21:41:00 67658 8192000000 10272807808 10720751616 1307393392 13.762 95 14 28 0 1320387712 9872016 3122304
21:43:11 100883 8192000000 10268885152 10717683712 1349083408 14.139 99 37 73 2 1369969952 12121024 8765520
21:45:53 73753 8192000000 10274552880 10729054208 1335283168 14.015 97 14 28 2 1352125728 13735952 3106608
21:48:30 95062 8192000000 10276031648 10735636480 1345678656 14.109 98 19 38 2 1367648480 17501296 4468528
21:51:33 54782 8192000000 10247824272 10702221312 1319565824 13.873 97 12 24 0 1329111040 7093504 2451712
21:54:09 38185 8192000000 10245672624 10693062656 1341759616 14.073 100 6 12 0 1351031280 7649824 1621840
21:55:47 51522 8192000000 10261135552 10716286976 1363199152 14.266 100 15 30 1 1376103040 9085168 3818720
21:57:41 56626 8192000000 10242621360 10706620416 1329726080 13.965 98 12 24 2 1347381616 14728032 2927504
21:59:39 82682 8192000000 10259403152 10720034816 1348201200 14.131 100 16 32 2 1362257280 10868832 3187248
22:02:08 59990 8192000000 10283528048 10742620160 1374473568 14.367 100 13 26 0 1392018848 14212560 3332720
22:04:13 60635 8192000000 10294276048 10749624320 1365917760 14.290 99 17 34 1 1385457184 15829056 3710368
22:06:14 62757 8192000000 10291903488 10760683520 1368407280 14.313 99 14 28 2 1386686992 15299824 2979888
22:08:18 57258 8192000000 10307178304 10755784704 1378509600 14.403 99 30 60 2 1405857184 20538176 6809408
22:10:16 93264 8192000000 10310851328 10766462976 1394349424 14.545 100 10 20 0 1412305056 16032896 1922736
22:12:50 117773 8192000000 10292424896 10744025088 1373015104 14.354 98 13 25 0 1390371488 14630768 2725616
22:15:52 40204 8192000000 10286192096 10754539520 1382048224 14.435 99 21 41 0 1394592768 7607120 4937424
22:17:33 81112 8192000000 10291461120 10753208320 1352001136 14.165 97 3 6 0 1359891024 7331856 558032
22:20:05 62435 8192000000 10311683376 10769592320 1393635280 14.538 99 21 42 0 1406108208 7511728 4961200
22:22:14 50587 8192000000 10286989056 10733400064 1298561760 13.682 93 20 40 1 1315943232 13072912 4308560
22:24:05 88127 8192000000 10300412592 10741542912 1339839440 14.056 97 15 31 0 1353052320 9946320 3266560
22:26:37 78316 8192000000 10293703104 10732679168 1287574752 13.582 93 30 60 0 1309403072 14780816 7047504
22:28:56 124813 8192000000 10293476768 10738438144 1342767664 14.082 98 15 30 0 1367145344 20750528 3627152
22:32:01 72306 8192000000 10272339072 10723004416 1308822608 13.775 96 16 32 2 1322170208 9818480 3529120
22:34:14 79385 8192000000 10221810944 10665562112 1301715536 13.711 96 32 63 0 1315220736 6058928 7446272
22:36:40 46560 8192000000 10241647952 10689908736 1341558496 14.071 100 23 46 1 1356943184 10293776 5090912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-05-2022 22:40:35 to 17-05-2022 22:40:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-05-2022 22:40:50 to 17-05-2022 22:40:52.

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          243247
MON$OLDEST_ACTIVE               245279
MON$OLDEST_SNAPSHOT             245279
MON$NEXT_TRANSACTION            245279
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                       2054944
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33592 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33592 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33592 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33592 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 ---
:44: 
:45: mscs-test-161.budzdorov.ru    Tue May 17 22:38:14 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Tue May 17 22:38:29 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
--- end of diff output ---

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

17.05.22 22:40:53. 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-05-17 22:40:56 No severe PSQL-related problems occured
17.05.22 22:40:57. End of report.