Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.07.2022 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16116120 kB
MemAvailable 31071056 kB
Buffers 0 kB
Cached 15578868 kB
SwapCached 2492 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-15 19:38:32
DTS_END                         2022-07-15 19:38:32.1900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45300
TRN_ID                          251271
ATT_ID                          696
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: 9194.33 20663 1103319
customer order: creation 184.58 353 22150
customer order: refuse 15.85 370 1902
order to supplier: creation 47.49 1299 5699
order to supplier: removal 3.09 107448 371
invoice (draft): creation 49.03 3326 5884
invoice (draft): removal 47.68 797 5721
invoice accept: apply 45.06 18325 5407
invoice accept: cancel 43.31 813 5197
customer reserve: creation 4163.25 182 499590
customer reserve: removal 4291.44 62 514973
realization accept: apply 88.90 180 10668
realization accept: cancel 78.55 112 9426
payment from customer: creation 61.63 117 7395
payment from customer: removal 6.35 79 762
payment to supplier: creation 51.79 125 6215
payment to supplier: removal 3.50 79 420
service: total inventory turnovers 4.97 1004 597
service: total monetary turnovers 7.03 33 843
service: aggregate perf. data 0.79 21568 95
service: refresh index statistics 0.03 256994 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-07-2022 19:39:36 to 15-07-2022 19:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4502
TEST_TIME 2 4327
TEST_TIME 3 3944
TEST_TIME 4 4314
TEST_TIME 5 4830
TEST_TIME 6 4805
TEST_TIME 7 4836
TEST_TIME 8 5341
TEST_TIME 9 5370
TEST_TIME 10 5212
TEST_TIME 11 5606
TEST_TIME 12 5790
TEST_TIME 13 5837
TEST_TIME 14 6148
TEST_TIME 15 6669
TEST_TIME 16 6655
TEST_TIME 17 7351
TEST_TIME 18 7380
TEST_TIME 19 7738
TEST_TIME 20 8167
TEST_TIME 21 8127
TEST_TIME 22 8454
TEST_TIME 23 8560
TEST_TIME 24 8526
TEST_TIME 25 8588
TEST_TIME 26 8743
TEST_TIME 27 9227
TEST_TIME 28 10039
TEST_TIME 29 10004
TEST_TIME 30 10374
TEST_TIME 31 10602
TEST_TIME 32 10580
TEST_TIME 33 10302
TEST_TIME 34 10168
TEST_TIME 35 10057
TEST_TIME 36 9910
TEST_TIME 37 9951
TEST_TIME 38 9966
TEST_TIME 39 9831
TEST_TIME 40 9835
TEST_TIME 41 9777
TEST_TIME 42 9711
TEST_TIME 43 9669
TEST_TIME 44 9599
TEST_TIME 45 9484
TEST_TIME 46 9292
TEST_TIME 47 9328
TEST_TIME 48 9383
TEST_TIME 49 9283
TEST_TIME 50 9283
TEST_TIME 51 9225
TEST_TIME 52 9213
TEST_TIME 53 9270
TEST_TIME 54 9241
TEST_TIME 55 9180
TEST_TIME 56 9251
TEST_TIME 57 9340
TEST_TIME 58 9334
TEST_TIME 59 9348
TEST_TIME 60 9496
TEST_TIME 61 9444
TEST_TIME 62 9505
TEST_TIME 63 9547
TEST_TIME 64 9553
TEST_TIME 65 9587
TEST_TIME 66 9660
TEST_TIME 67 9623
TEST_TIME 68 9549
TEST_TIME 69 9533
TEST_TIME 70 9475
TEST_TIME 71 9493
TEST_TIME 72 9450
TEST_TIME 73 9426
TEST_TIME 74 9387
TEST_TIME 75 9388
TEST_TIME 76 9299
TEST_TIME 77 9268
TEST_TIME 78 9223
TEST_TIME 79 9205
TEST_TIME 80 9277
TEST_TIME 81 9267
TEST_TIME 82 9257
TEST_TIME 83 9259
TEST_TIME 84 9384
TEST_TIME 85 9374
TEST_TIME 86 9367
TEST_TIME 87 9354
TEST_TIME 88 9373
TEST_TIME 89 9409
TEST_TIME 90 9420
TEST_TIME 91 9378
TEST_TIME 92 9318
TEST_TIME 93 9307
TEST_TIME 94 9306
TEST_TIME 95 9239
TEST_TIME 96 9298
TEST_TIME 97 9269
TEST_TIME 98 9239
TEST_TIME 99 9233
TEST_TIME 100 9275
TEST_TIME 101 9297
TEST_TIME 102 9296
TEST_TIME 103 9329
TEST_TIME 104 9364
TEST_TIME 105 9395
TEST_TIME 106 9381
TEST_TIME 107 9391
TEST_TIME 108 9396
TEST_TIME 109 9421
TEST_TIME 110 9361
TEST_TIME 111 9290
TEST_TIME 112 9267
TEST_TIME 113 9260
TEST_TIME 114 9214
TEST_TIME 115 9214
TEST_TIME 116 9185
TEST_TIME 117 9201
TEST_TIME 118 9199
TEST_TIME 119 9162
TEST_TIME 120 9199

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-07-2022 19:39:39 to 15-07-2022 19:39:43.

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 1563520 1563520 0 0.00 0 15493 7 0 0
doc_list_biud 1563518 1563518 0 0.00 0 20044 26 0 0
sp_add_invoice_to_stock 5556 5407 149 2.68 25 187179 18325 0 149
sp_cancel_adding_invoice 5197 5197 0 0.00 5 71334 813 0 0
sp_cancel_client_order 1902 1902 0 0.00 4 12090 371 0 0
sp_cancel_customer_reserve 514973 514973 0 0.00 0 20048 62 0 0
sp_cancel_pay_from_customer 762 762 0 0.00 2 10255 80 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 2 5350 80 0 0
sp_cancel_supplier_invoice 5738 5721 17 0.29 2 17053 797 0 17
sp_cancel_supplier_order 371 371 0 0.00 10 1017981 107449 0 0
sp_cancel_write_off 9426 9426 0 0.00 1 6449 113 0 0
sp_client_order 22150 22150 0 0.00 3 19578 354 0 0
sp_customer_reserve 499590 499590 0 0.00 0 90170 183 0 0
sp_fill_shopping_cart 33726 33726 0 0.00 1 16256 40 0 0
sp_fill_shopping_cart_clo_res 499582 499582 0 0.00 0 18429 14 0 0
sp_get_clo_for_invoice 5399 5399 0 0.00 0 27692 284 0 0
sp_kill_cost_storno 15805 15805 0 0.00 0 10250 53 0 0
sp_kill_qstorno_ret_qs2qd 1045464 1045464 0 0.00 0 20037 33 0 0
sp_kill_qty_storno 553656 553656 0 0.00 0 20044 72 0 0
sp_lock_selected_doc 22087 22087 0 0.00 0 11639 6 0 0
sp_make_cost_storno 29677 29677 0 0.00 0 14202 53 0 0
sp_multiply_rows_for_pdistr 29677 29677 0 0.00 0 9842 26 0 0
sp_multiply_rows_for_qdistr 22150 22150 0 0.00 0 13973 158 0 0
sp_pay_from_customer 7395 7395 0 0.00 4 11343 117 0 0
sp_pay_to_supplier 6215 6215 0 0.00 4 12624 125 0 0
sp_payment_common 13609 13609 0 0.00 1 8678 31 0 0
sp_qd_handle_on_cancel_clo 1902 1902 0 0.00 0 12084 341 0 0
sp_qd_handle_on_invoice_upd_sts 10596 10596 0 0.00 0 18982 314 0 0
sp_qd_handle_on_reserve_upd_sts 10667 10667 0 0.00 0 13113 65 0 0
sp_reserve_write_off 10668 10668 0 0.00 6 13200 180 0 0
sp_supplier_invoice 5884 5884 0 0.00 7 85032 3327 0 0
sp_supplier_order 5699 5699 0 0.00 4 37647 1299 0 0
srv_aggregate_perf_data 95 95 0 0.00 2162 468831 21568 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1811 118158 38294 0 0
srv_find_qd_qs_mism 1051197 1051197 0 0.00 0 14238 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 23 46917 1005 0 0
srv_make_money_saldo 843 843 0 0.00 1 5385 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 156924 336063 256995 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5407 5407 0 0.00 0 9472 166 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 2705 100 0 0
t$perf-norm:sp_cancel_client_order 1902 1902 0 0.00 0 2759 13 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 7100 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 762 762 0 0.00 0 4402 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 828 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1790 14 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 25719 1743 0 0
t$perf-norm:sp_cancel_write_off 1074 1074 0 0.00 0 3308 10 0 0
t$perf-norm:sp_client_order 22150 22150 0 0.00 0 6956 17 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 1583 8 0 0
t$perf-norm:sp_pay_from_customer 7395 7395 0 0.00 0 9344 14 0 0
t$perf-norm:sp_pay_to_supplier 6215 6215 0 0.00 0 7907 14 0 0
t$perf-norm:sp_reserve_write_off 10668 10668 0 0.00 0 8198 12 0 0
t$perf-norm:sp_supplier_invoice 5884 5884 0 0.00 0 7843 19 0 0
t$perf-norm:sp_supplier_order 5699 5699 0 0.00 0 7775 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 23 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 31 1 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 1060 21 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 1954 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 11096 10798 298 2.68 0 11572 18 0 298
v_all_customers 39924 39924 0 0.00 0 19 1 0 0
v_all_suppliers 32072 32072 0 0.00 0 48 1 0 0
v_all_wares 44298 44298 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 7313 21 0 0
v_cancel_client_order 3804 3804 0 0.00 0 2362 6 0 0
v_cancel_customer_prepayment 1524 1524 0 0.00 0 4436 8 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 2612 8 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2614 8 0 0
v_cancel_supplier_invoice 840 806 34 4.04 0 4219 19 0 34
v_cancel_supplier_order 742 742 0 0.00 0 229 3 0 0
v_cancel_write_off 2148 2148 0 0.00 0 1347 13 0 0
v_max_id_clo_ord 5698 5698 0 0.00 0 416 1 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 1396 13 0 0
v_max_id_ord_sup 5879 5879 0 0.00 0 6949 7 0 0
v_max_non_paid_invoice 1756 1756 0 0.00 0 997 4 0 0
v_max_non_paid_realizn 4024 4024 0 0.00 0 3794 5 0 0
v_min_id_clo_ord 5698 5698 0 0.00 0 4150 6 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 5151 14 0 0
v_min_id_ord_sup 5879 5879 0 0.00 0 4985 6 0 0
v_min_non_paid_invoice 6215 6215 0 0.00 0 8670 23 0 0
v_min_non_paid_realizn 7394 7394 0 0.00 0 7183 24 0 0
v_our_firm 11114 11114 0 0.00 0 59 1 0 0
v_reserve_write_off 21332 21332 0 0.00 0 11588 6 0 0
x_lock_depdocs_on_canc_invoice 10515 10515 0 0.00 0 34909 125 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 6941 367 0 0
x_make_qstorno_1000_1200 5699 5699 0 0.00 1 37619 1228 0 0
x_make_qstorno_1200_2000 5884 5884 0 0.00 3 84993 3171 0 0
x_make_qstorno_2100_3300 499590 499590 0 0.00 0 90153 166 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 15-07-2022 19:39:43 to 15-07-2022 19:39:48.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:37 32234 8192000000 9923800592 10238787584 1002244320 10.900 100 88 176 0 1031916368 6561360 23110688
17:11:18 14486 8192000000 9975160720 10304733184 1058806080 11.445 100 88 175 0 1090505488 9224528 22474880
17:12:46 19661 8192000000 10009543184 10352316416 1093882832 11.780 100 80 160 0 1124232672 10511168 19838672
17:14:11 8253 8192000000 10051034944 10403381248 1139371888 12.210 100 76 153 0 1168226256 11029760 17824608
17:15:25 4879 8192000000 10101064128 10466762752 1192095696 12.703 100 80 160 1 1222352448 11618656 18638096
17:16:31 3158 8192000000 10138720672 10517102592 1237374688 13.122 100 75 148 0 1265630624 11606624 16649312
17:17:35 1927 8192000000 10172322304 10550554624 1258654112 13.318 100 85 170 0 1292429200 15306432 18468656
17:18:39 1493 8192000000 10199586080 10584526848 1285435136 13.563 100 81 162 0 1316719680 13225744 18058800
17:19:41 875 8192000000 10217963232 10603069440 1301675936 13.710 100 91 179 1 1334334496 12160288 20498272
17:20:43 6851 8192000000 10222020784 10616815616 1311562256 13.800 100 66 127 0 1334214480 8567056 14085168
17:21:51 11206 8192000000 10195808112 10616139776 1314068272 13.823 100 24 49 0 1325826320 6093856 5664192
17:23:06 11624 8192000000 10196117216 10618875904 1315605376 13.837 100 15 30 0 1324652816 5388192 3659248
17:24:20 32660 8192000000 10211863712 10629615616 1320025920 13.877 100 14 30 1 1327737072 4539536 3171616
17:25:58 5870 8192000000 10216487104 10640015360 1317967008 13.858 99 23 46 2 1329636816 6147088 5522720
17:27:06 10393 8192000000 10209579424 10637193216 1291621568 13.619 97 33 65 1 1309226112 9765344 7839200
17:28:17 15245 8192000000 10207417328 10630180864 1300195168 13.697 98 10 20 0 1307757776 5450432 2112176
17:29:41 43816 8192000000 10168731600 10580578304 1240457648 13.150 95 19 38 0 1249827280 4921200 4448432
17:31:25 9019 8192000000 10159954224 10568613888 1254625488 13.281 99 36 72 1 1276887360 14557968 7703904
17:32:34 9287 8192000000 10126468768 10524831744 1187162096 12.657 95 19 39 0 1196756128 4995328 4598704
17:33:45 6975 8192000000 10157188992 10561941504 1254765856 13.282 99 30 59 1 1267158064 5258688 7133520
17:34:53 44472 8192000000 10187319616 10595803136 1274520400 13.463 99 29 57 1 1291082592 9103360 7458832
17:36:42 8354 8192000000 10186446224 10604695552 1300221664 13.697 100 16 32 0 1309608960 5450368 3936928
17:37:54 6790 8192000000 10204177632 10621984768 1312650160 13.810 100 25 51 1 1324179680 5228304 6301216
17:39:09 28274 8192000000 10243398880 10651938816 1329485360 13.963 99 49 98 0 1348489504 7602192 11401952
17:40:56 64716 8192000000 10261716688 10686017536 1355599392 14.198 100 33 66 0 1374787776 11124992 8063392
17:43:04 41086 8192000000 10270237696 10692882432 1346078064 14.112 98 19 38 0 1362018928 11323184 4617680
17:44:49 8315 8192000000 10295590640 10718806016 1383943632 14.452 100 45 89 0 1410071392 15643360 10484400
17:46:03 2269 8192000000 10330574624 10749386752 1401762464 14.611 100 65 129 0 1437317280 20593568 14961248
17:47:10 21178 8192000000 10323774240 10748833792 1392058576 14.524 99 57 114 0 1426060032 20690976 13310480
17:48:46 4091 8192000000 10330934000 10752102400 1391885584 14.523 99 65 128 2 1431589584 24467792 15236208
17:49:56 8190 8192000000 10338039632 10759340032 1406228656 14.650 100 58 116 0 1446398144 26580576 13588912
17:51:10 2150 8192000000 10345799616 10758361088 1402730800 14.619 100 81 159 1 1450695152 29058032 18906320
17:52:18 2433 8192000000 10347248496 10761224192 1390626176 14.511 99 71 139 1 1443428912 36131696 16671040
17:53:28 3777 8192000000 10341683232 10760208384 1400170368 14.597 100 64 125 1 1450631632 34314320 16146944
17:54:39 3136 8192000000 10317494512 10730299392 1367654496 14.306 99 59 117 0 1415776144 33564960 14556688
17:55:47 3562 8192000000 10314729920 10723504128 1353567392 14.180 99 66 130 1 1411659568 40886624 17205552
17:56:56 1808 8192000000 10344305280 10746056704 1380811264 14.424 100 89 174 2 1442817120 39689984 22315872
17:58:04 4885 8192000000 10326305744 10737864704 1376798048 14.388 100 59 118 0 1430088752 38083696 15207008
17:59:19 2636 8192000000 10319638400 10729836544 1371522256 14.341 100 65 123 2 1423315376 35964464 15828656
18:00:26 8255 8192000000 10326688048 10744827904 1379353648 14.411 100 52 102 2 1432213120 40002112 12857360
18:01:38 10861 8192000000 10334720816 10765635584 1383573568 14.448 99 37 74 0 1431416112 37939376 9903168
18:02:57 3629 8192000000 10343109104 10757414912 1386377248 14.474 100 57 112 1 1441457632 40454320 14626064
18:04:04 17507 8192000000 10327845280 10758844416 1379172768 14.409 100 33 66 0 1433362608 44209472 9980368
18:05:39 12066 8192000000 10321721728 10751340544 1362986976 14.264 99 32 65 0 1416120144 43718288 9414880
18:08:42 28126 8192000000 10283920672 10726875136 1354155408 14.185 99 17 35 0 1393116080 34883264 4077408
18:10:22 109997 8192000000 10282170592 10720854016 1361751376 14.253 99 28 50 1 1377848544 10349392 5747776
18:13:21 26510 8192000000 10302369424 10739744768 1383836256 14.451 100 40 76 3 1400373440 7922144 8615040
18:15:01 76027 8192000000 10290379904 10741592064 1375526176 14.377 99 29 59 1 1396706720 14375680 6804864
18:17:21 70442 8192000000 10290205168 10739658752 1362625616 14.261 98 27 54 0 1376337264 7673600 6038048
18:19:39 32320 8192000000 10293541872 10737311744 1365233232 14.284 98 26 52 0 1377556080 6648368 5674480
18:22:58 37755 8192000000 10306538064 10765176832 1404177584 14.632 100 17 34 0 1418138832 10192352 3768896
18:24:38 57464 8192000000 10302410896 10760581120 1390093184 14.507 99 27 54 0 1410453664 13281168 7079312
18:26:37 57090 8192000000 10286482928 10748792832 1380994384 14.425 99 10 20 0 1390930000 7788160 2147456
18:28:37 52428 8192000000 10293391680 10753544192 1372504080 14.349 98 18 36 0 1387980736 10697872 4778784
18:30:30 46275 8192000000 10279611216 10749968384 1384770816 14.459 100 23 44 0 1402079152 11946320 5362016
18:32:21 45844 8192000000 10293030688 10751406080 1373607648 14.359 99 19 36 0 1386497072 8963776 3925648
18:34:17 35875 8192000000 10288564512 10742308864 1361043680 14.247 98 20 40 0 1381892640 16257104 4591856
18:35:56 23996 8192000000 10298788544 10745458688 1381898720 14.434 100 45 89 4 1402828880 10612768 10317392
18:37:22 57716 8192000000 10289985184 10740256768 1355062656 14.193 98 24 48 0 1376686192 16080672 5542864
18:39:26 49216 8192000000 10289294256 10746429440 1367874720 14.308 99 20 40 0 1386576608 14150144 4551744
18:41:19 34783 8192000000 10261035840 10699907072 1309696304 13.783 96 34 68 0 1333096080 16447760 6952016
18:42:56 75661 8192000000 10249440192 10706464768 1306598864 13.755 96 19 39 0 1321934208 10585088 4750256
18:45:14 66040 8192000000 10251632144 10705928192 1343226928 14.086 100 21 40 0 1359840816 11797184 4816704
18:47:56 34718 8192000000 10258413808 10695704576 1331185200 13.978 99 31 61 0 1347014848 8517744 7311904
18:49:36 34843 8192000000 10235147168 10686402560 1315810864 13.839 97 22 42 0 1330171328 9080000 5280464
18:51:14 28783 8192000000 10257917840 10707202048 1344705376 14.100 99 21 42 1 1360468960 11305696 4457888
18:52:49 58108 8192000000 10238372752 10698252288 1332206432 13.987 98 9 18 0 1342631520 8222496 2202592
18:54:52 62371 8192000000 10234073440 10686652416 1328727456 13.956 98 13 26 0 1338933456 7574336 2631664
18:56:55 40912 8192000000 10267520880 10725011456 1367273600 14.303 100 16 32 0 1382316512 11780960 3261952
18:59:13 37762 8192000000 10264052224 10714836992 1330569552 13.972 97 20 40 0 1345071920 10303536 4198832
19:00:59 61805 8192000000 10281676368 10738835456 1365938384 14.291 99 23 46 0 1388407568 17289344 5179840
19:03:33 29000 8192000000 10272483248 10715181056 1350846544 14.155 99 15 30 0 1362864512 8870784 3147184
19:05:08 50590 8192000000 10293508960 10734145536 1368050192 14.310 99 30 60 0 1384466032 9929936 6485904
19:07:28 80657 8192000000 10289434368 10748735488 1364127168 14.274 98 27 54 1 1383146528 13719376 5299984
19:09:54 82325 8192000000 10306405328 10761449472 1378128128 14.400 99 36 72 1 1404941632 19034352 7779152
19:12:24 48929 8192000000 10292848256 10752434176 1383018912 14.444 99 7 15 0 1400569792 16018352 1532528
19:14:13 48843 8192000000 10284444000 10764361728 1381898768 14.434 99 6 12 0 1398575584 15196064 1480752
19:16:23 25317 8192000000 10282898016 10734723072 1359108928 14.229 97 21 43 0 1373845536 9892144 4844464
19:17:52 44171 8192000000 10264560448 10715389952 1297320912 13.671 93 23 46 0 1313317024 10825344 5170768
19:19:41 38544 8192000000 10271749040 10720043008 1336352944 14.025 97 24 48 0 1356415728 14273744 5789040
19:21:20 118157 8192000000 10280738560 10750816256 1305471664 13.745 95 11 22 0 1323935648 16173392 2290592
19:24:18 37489 8192000000 10279840448 10713890816 1315338544 13.834 96 45 90 0 1335801568 10417648 10045376
19:26:01 34198 8192000000 10266898176 10704736256 1330144992 13.968 98 25 50 0 1343929504 8901568 4882944
19:27:35 76019 8192000000 10244231392 10694819840 1268207824 13.405 94 26 52 0 1297942576 24093296 5641456
19:30:40 25528 8192000000 10245005472 10694979584 1336035440 14.022 99 20 41 0 1347903248 7055520 4812288
19:32:12 49567 8192000000 10247154864 10709942272 1332212512 13.987 98 6 12 0 1341323872 7877616 1233744
19:34:15 49518 8192000000 10274608400 10726998016 1367709040 14.307 100 26 54 0 1381276544 7937120 5630384
19:36:09 72929 8192000000 10253587584 10705940480 1304603792 13.737 96 15 30 0 1321043472 13075520 3364160
19:38:22 17817 8192000000 8971124832 9160425472 142170368 1.705 11 3 4 0 145608480 3098672 339440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-07-2022 19:39:49 to 15-07-2022 19:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 149 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-07-2022 19:40:05 to 15-07-2022 19:40:07.

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          251575
MON$OLDEST_ACTIVE               251576
MON$OLDEST_SNAPSHOT             251576
MON$NEXT_TRANSACTION            251576
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                       2025760
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 ---
:1127: 
:1128: mscs-test-161.budzdorov.ru    Fri Jul 15 19:38:46 2022
:1129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1130: 
--- end of diff output ---

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

15.07.22 19:40:08. 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-15 19:40:11 No severe PSQL-related problems occured
15.07.22 19:40:12. End of report.