Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.01.2023 07:37
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 15111856 kB
MemAvailable 30403060 kB
Buffers 0 kB
Cached 16572248 kB
SwapCached 1644 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2023-01-21 07:37:24
DTS_END                         2023-01-21 07:37:24.5460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48318
TRN_ID                          214674
ATT_ID                          659
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: 9291.08 25993 1114930
customer order: creation 164.77 483 19772
customer order: refuse 14.23 415 1707
order to supplier: creation 44.21 1419 5305
order to supplier: removal 3.38 190547 406
invoice (draft): creation 44.91 3137 5389
invoice (draft): removal 53.03 936 6364
invoice accept: apply 40.88 24907 4905
invoice accept: cancel 48.14 1220 5777
customer reserve: creation 3849.75 234 461970
customer reserve: removal 4740.99 110 568919
realization accept: apply 80.55 261 9666
realization accept: cancel 80.78 172 9694
payment from customer: creation 57.21 164 6865
payment from customer: removal 5.63 109 675
payment to supplier: creation 46.76 180 5611
payment to supplier: removal 3.08 114 370
service: total inventory turnovers 4.24 3516 509
service: total monetary turnovers 7.73 338 927
service: aggregate perf. data 0.79 18055 95
service: refresh index statistics 0.03 273550 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-01-2023 07:38:19 to 21-01-2023 07:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6708
TEST_TIME 2 5440
TEST_TIME 3 5063
TEST_TIME 4 5731
TEST_TIME 5 6228
TEST_TIME 6 6159
TEST_TIME 7 6346
TEST_TIME 8 6250
TEST_TIME 9 6267
TEST_TIME 10 6378
TEST_TIME 11 6618
TEST_TIME 12 6688
TEST_TIME 13 6615
TEST_TIME 14 6813
TEST_TIME 15 6862
TEST_TIME 16 7090
TEST_TIME 17 7113
TEST_TIME 18 7078
TEST_TIME 19 7039
TEST_TIME 20 7426
TEST_TIME 21 7527
TEST_TIME 22 7730
TEST_TIME 23 7901
TEST_TIME 24 8201
TEST_TIME 25 8775
TEST_TIME 26 8959
TEST_TIME 27 9110
TEST_TIME 28 9665
TEST_TIME 29 10179
TEST_TIME 30 10428
TEST_TIME 31 10981
TEST_TIME 32 11476
TEST_TIME 33 11251
TEST_TIME 34 11177
TEST_TIME 35 10889
TEST_TIME 36 10713
TEST_TIME 37 10930
TEST_TIME 38 10686
TEST_TIME 39 10600
TEST_TIME 40 10468
TEST_TIME 41 10348
TEST_TIME 42 10574
TEST_TIME 43 10496
TEST_TIME 44 10360
TEST_TIME 45 10306
TEST_TIME 46 10200
TEST_TIME 47 10132
TEST_TIME 48 10136
TEST_TIME 49 10042
TEST_TIME 50 10019
TEST_TIME 51 10025
TEST_TIME 52 10010
TEST_TIME 53 9993
TEST_TIME 54 9923
TEST_TIME 55 9958
TEST_TIME 56 9967
TEST_TIME 57 10175
TEST_TIME 58 10229
TEST_TIME 59 10125
TEST_TIME 60 10009
TEST_TIME 61 9932
TEST_TIME 62 9918
TEST_TIME 63 9899
TEST_TIME 64 9814
TEST_TIME 65 9711
TEST_TIME 66 9628
TEST_TIME 67 9687
TEST_TIME 68 9654
TEST_TIME 69 9606
TEST_TIME 70 9590
TEST_TIME 71 9558
TEST_TIME 72 9501
TEST_TIME 73 9487
TEST_TIME 74 9459
TEST_TIME 75 9483
TEST_TIME 76 9419
TEST_TIME 77 9434
TEST_TIME 78 9556
TEST_TIME 79 9647
TEST_TIME 80 9916
TEST_TIME 81 9837
TEST_TIME 82 9787
TEST_TIME 83 9763
TEST_TIME 84 9712
TEST_TIME 85 9663
TEST_TIME 86 9606
TEST_TIME 87 9600
TEST_TIME 88 9566
TEST_TIME 89 9556
TEST_TIME 90 9598
TEST_TIME 91 9572
TEST_TIME 92 9547
TEST_TIME 93 9585
TEST_TIME 94 9546
TEST_TIME 95 9543
TEST_TIME 96 9555
TEST_TIME 97 9538
TEST_TIME 98 9523
TEST_TIME 99 9570
TEST_TIME 100 9567
TEST_TIME 101 9503
TEST_TIME 102 9474
TEST_TIME 103 9409
TEST_TIME 104 9391
TEST_TIME 105 9367
TEST_TIME 106 9357
TEST_TIME 107 9354
TEST_TIME 108 9297
TEST_TIME 109 9287
TEST_TIME 110 9265
TEST_TIME 111 9241
TEST_TIME 112 9206
TEST_TIME 113 9233
TEST_TIME 114 9214
TEST_TIME 115 9240
TEST_TIME 116 9212
TEST_TIME 117 9272
TEST_TIME 118 9354
TEST_TIME 119 9341
TEST_TIME 120 9317

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-01-2023 07:38:23 to 21-01-2023 07:38:26.

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 1540265 1540265 0 0.00 0 16029 12 0 0
doc_list_biud 1540260 1540260 0 0.00 0 77088 45 0 0
sp_add_invoice_to_stock 5036 4905 131 2.60 28 237452 24908 0 131
sp_cancel_adding_invoice 5777 5777 0 0.00 6 101905 1221 0 0
sp_cancel_client_order 1707 1707 0 0.00 6 16065 415 0 0
sp_cancel_customer_reserve 568919 568919 0 0.00 1 77110 110 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 4 2011 110 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 4157 115 0 0
sp_cancel_supplier_invoice 6377 6364 13 0.20 2 18959 936 0 13
sp_cancel_supplier_order 406 406 0 0.00 11 1417222 190547 0 0
sp_cancel_write_off 9694 9694 0 0.00 1 10874 172 0 0
sp_client_order 19772 19772 0 0.00 5 31724 483 0 0
sp_customer_reserve 461970 461970 0 0.00 0 74471 235 0 0
sp_fill_shopping_cart 30460 30460 0 0.00 1 23441 82 0 0
sp_fill_shopping_cart_clo_res 461946 461946 0 0.00 0 18265 19 0 0
sp_get_clo_for_invoice 4866 4866 0 0.00 0 20745 287 0 0
sp_kill_cost_storno 16516 16516 0 0.00 0 9912 69 0 0
sp_kill_qstorno_ret_qs2qd 1154302 1154302 0 0.00 0 77038 47 0 0
sp_kill_qty_storno 607397 607397 0 0.00 0 77085 107 0 0
sp_lock_selected_doc 20016 20016 0 0.00 0 6566 6 0 0
sp_make_cost_storno 27006 27006 0 0.00 0 14944 73 0 0
sp_multiply_rows_for_pdistr 27006 27006 0 0.00 0 9139 36 0 0
sp_multiply_rows_for_qdistr 19772 19772 0 0.00 0 14333 196 0 0
sp_pay_from_customer 6865 6865 0 0.00 5 14800 165 0 0
sp_pay_to_supplier 5611 5611 0 0.00 7 14951 181 0 0
sp_payment_common 12476 12476 0 0.00 2 6815 42 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 1 15969 345 0 0
sp_qd_handle_on_invoice_upd_sts 10641 10641 0 0.00 0 40241 498 0 0
sp_qd_handle_on_reserve_upd_sts 9666 9666 0 0.00 0 19810 57 0 0
sp_reserve_write_off 9666 9666 0 0.00 8 19835 262 0 0
sp_supplier_invoice 5389 5389 0 0.00 10 112067 3137 0 0
sp_supplier_order 5305 5305 0 0.00 8 105938 1420 0 0
srv_aggregate_perf_data 95 95 0 0.00 2873 154418 18055 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 830 148743 34820 0 0
srv_find_qd_qs_mism 1067080 1067080 0 0.00 0 16026 9 0 0
srv_make_invnt_saldo 509 509 0 0.00 51 104254 3516 0 0
srv_make_money_saldo 927 927 0 0.00 1 13853 339 0 0
srv_recalc_idx_stat 4 4 0 0.00 85616 546946 273550 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4912 4912 0 0.00 1 10998 840 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 8923 529 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 23489 39 0 0
t$perf-norm:sp_cancel_customer_reserve 589 589 0 0.00 0 2410 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 901 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 1557 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 755 36 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 86296 6598 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 1379 18 0 0
t$perf-norm:sp_client_order 19772 19772 0 0.00 0 25982 37 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 3488 17 0 0
t$perf-norm:sp_pay_from_customer 6865 6865 0 0.00 0 5488 18 0 0
t$perf-norm:sp_pay_to_supplier 5611 5611 0 0.00 0 5286 19 0 0
t$perf-norm:sp_reserve_write_off 9666 9666 0 0.00 0 6893 25 0 0
t$perf-norm:sp_supplier_invoice 5389 5389 0 0.00 0 7787 30 0 0
t$perf-norm:sp_supplier_order 5305 5305 0 0.00 0 7963 29 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 753 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 402 12 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 2779 16 0 0
t$perf-norm:srv_make_money_saldo 927 927 0 0.00 0 2152 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 175 56 0 0
v_add_invoice_to_stock 9990 9728 262 2.62 0 6425 25 0 262
v_all_customers 36632 36632 0 0.00 0 154 4 0 0
v_all_suppliers 30018 30018 0 0.00 0 234 4 0 0
v_all_wares 39544 39544 0 0.00 0 152 4 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 1000 14 0 0
v_cancel_client_order 3414 3414 0 0.00 0 2212 13 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 1943 12 0 0
v_cancel_customer_reserve 1178 1178 0 0.00 0 1107 11 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 385 10 0 0
v_cancel_supplier_invoice 746 720 26 3.48 0 1733 25 0 26
v_cancel_supplier_order 812 812 0 0.00 0 1401 13 0 0
v_cancel_write_off 1954 1954 0 0.00 0 1678 22 0 0
v_max_id_clo_ord 5305 5305 0 0.00 0 155 5 0 0
v_max_id_clo_res 948 948 0 0.00 0 4024 23 0 0
v_max_id_ord_sup 5383 5383 0 0.00 0 4333 8 0 0
v_max_non_paid_invoice 1290 1290 0 0.00 0 3903 11 0 0
v_max_non_paid_realizn 3377 3377 0 0.00 0 2088 8 0 0
v_min_id_clo_ord 5305 5305 0 0.00 0 5399 9 0 0
v_min_id_clo_res 948 948 0 0.00 0 5849 23 0 0
v_min_id_ord_sup 5383 5383 0 0.00 0 9501 18 0 0
v_min_non_paid_invoice 5611 5611 0 0.00 0 6081 16 0 0
v_min_non_paid_realizn 6865 6865 0 0.00 0 6811 16 0 0
v_our_firm 9888 9888 0 0.00 0 88 4 0 0
v_reserve_write_off 19332 19332 0 0.00 0 12906 15 0 0
x_lock_depdocs_on_canc_invoice 11781 11781 0 0.00 0 14075 109 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 4251 320 0 0
x_make_qstorno_1000_1200 5305 5305 0 0.00 2 100500 1272 0 0
x_make_qstorno_1200_2000 5389 5389 0 0.00 4 102633 2899 0 0
x_make_qstorno_2100_3300 461970 461970 0 0.00 0 74470 205 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2023 07:38:28 to 21-01-2023 07:38:32.

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
05:07:28 7 8192000000 8803241520 8816222208 7613952 0.092 4 1 2 0 7748128 71952 62224
05:08:29 29063 8192000000 9727293552 10023161856 805771152 8.955 100 93 186 0 836463808 7849552 22843104
05:10:06 28335 8192000000 9773981360 10081509376 845512480 9.355 100 92 184 0 878872480 11941152 21418848
05:11:43 14988 8192000000 9800965760 10120974336 872231360 9.622 100 83 166 0 916009280 24917456 18860464
05:13:02 5561 8192000000 9874328832 10209411072 924640864 10.142 100 83 161 0 986286992 44706400 16939728
05:14:16 4741 8192000000 9921139840 10269020160 961096304 10.500 100 84 168 1 1037597328 58924048 17576976
05:15:23 1871 8192000000 9955670080 10310127616 988599504 10.768 100 90 176 0 1072702048 66547808 17554736
05:16:27 1413 8192000000 9995581456 10357321728 1010200736 10.977 100 86 165 3 1118942624 92035536 16706352
05:17:30 1177 8192000000 10000545232 10368454656 1039446640 11.259 100 94 182 1 1120573936 62869312 18257984
05:18:32 964 8192000000 10020306624 10390630400 1056127152 11.419 100 96 182 0 1140497488 67307856 17062480
05:19:34 6119 8192000000 10042010576 10424623104 1057016784 11.428 100 66 131 0 1168232656 98456368 12759504
05:20:42 5627 8192000000 10029883056 10411163648 1069157712 11.544 100 78 150 1 1155376704 70500272 15718720
05:21:49 1181 8192000000 10078185632 10460790784 1072128784 11.572 100 95 185 0 1195185376 104042128 19014464
05:22:51 1117 8192000000 10049702608 10431672320 1083099264 11.677 100 95 177 0 1174191008 71362672 19729072
05:23:53 4833 8192000000 10052973216 10443493376 1085817072 11.703 100 76 151 0 1176871264 76378288 14675904
05:25:02 4425 8192000000 10055040192 10453643264 1090744272 11.750 100 69 137 2 1175072032 70948944 13378816
05:26:07 7038 8192000000 10046446240 10445332480 1090545584 11.748 100 54 104 0 1156870704 56101632 10223488
05:27:15 1053 8192000000 10071610976 10458112000 1098832992 11.827 100 94 180 1 1197336848 79728016 18775840
05:28:18 908 8192000000 10045656176 10434506752 1084773648 11.693 100 91 162 0 1168355856 66487808 17094400
05:29:19 1472 8192000000 10037129824 10425966592 1088983632 11.733 100 78 156 0 1156779280 51771920 16023728
05:30:22 880 8192000000 10039976944 10429128704 1081974032 11.666 100 89 163 0 1162808592 62639824 18194736
05:31:23 2072 8192000000 10012736592 10393567232 1061315056 11.469 99 91 162 3 1134448736 56439472 16694208
05:32:27 1663 8192000000 10002236944 10388910080 1050653680 11.367 100 65 125 1 1127725888 62662592 14409616
05:33:29 1025 8192000000 9983473728 10356150272 1041397664 11.278 100 87 160 1 1103373264 45392384 16583216
05:34:31 6117 8192000000 9983803568 10368172032 1047059152 11.332 99 73 136 1 1101510496 40400400 14050944
05:35:39 8091 8192000000 9982327488 10371760128 1026132016 11.131 99 53 103 1 1092747568 56122480 10493072
05:36:48 1213 8192000000 9976882528 10346385408 1027822448 11.147 100 97 186 4 1102753424 55879072 19051904
05:37:53 3550 8192000000 9996666656 10380476416 1062982096 11.485 100 61 112 0 1120495728 45796784 11716848
05:39:01 3287 8192000000 10013447008 10401079296 1073816832 11.589 100 61 110 3 1131518272 46271248 11430192
05:40:43 6119 8192000000 10077002304 10457341952 1090217072 11.745 100 84 150 2 1185991632 82666800 13107760
05:42:03 4241 8192000000 10095250016 10493267968 1107135584 11.905 100 83 159 2 1224900688 99120512 18644592
05:43:13 16679 8192000000 10118216144 10519617536 1112211952 11.953 100 64 119 2 1237306864 112362784 12732128
05:44:36 1155 8192000000 10148749728 10549739520 1121710640 12.043 100 93 180 1 1263549680 120138816 21700224
05:45:39 1016 8192000000 10189795712 10580623360 1137837056 12.195 100 97 189 0 1303994384 145936224 20221104
05:46:46 3581 8192000000 10209120560 10629861376 1154103152 12.348 100 78 150 0 1328337296 156202320 18031824
05:47:58 823 8192000000 10259218352 10655686656 1169688784 12.494 100 94 181 2 1371486000 181622704 20174512
05:49:02 807 8192000000 10296056096 10695774208 1174156352 12.536 100 95 178 1 1402704480 208451584 20096544
05:50:06 2828 8192000000 10337485792 10742882304 1186661664 12.652 100 79 153 0 1452686352 248339088 17685600
05:51:13 854 8192000000 10381772432 10800549888 1192164128 12.703 100 98 190 1 1490836768 277182080 21490560
05:52:18 1769 8192000000 10406612016 10826899456 1201840400 12.793 100 97 182 1 1516482848 292877568 21764880
05:53:23 1584 8192000000 10468563216 10884960256 1213105936 12.898 100 93 186 1 1575873008 339764848 23002224
05:54:28 915 8192000000 10511193200 10919944192 1215000816 12.915 100 97 189 1 1615096080 377902592 22192672
05:55:31 898 8192000000 10565379968 10975973376 1209812960 12.867 100 97 188 0 1672471360 440195008 22463392
05:56:36 906 8192000000 10617996480 11036057600 1202573232 12.800 100 98 193 1 1722898128 497221184 23103712
05:57:39 2234 8192000000 10591959632 11037409280 1186686432 12.653 99 79 152 1 1699325056 493836016 18802608
05:58:49 1019 8192000000 10564390112 11017134080 1186955920 12.655 100 84 163 1 1672800144 463949312 21894912
05:59:56 877 8192000000 10593180144 11021426688 1180532496 12.595 100 93 180 1 1700056800 496685872 22838432
06:00:59 5316 8192000000 10607685472 11059916800 1183335104 12.621 100 76 152 0 1708298768 505750192 19213472
06:02:12 10754 8192000000 10577579408 11083587584 1176704720 12.559 100 52 104 1 1669494096 479365968 13423408
06:03:25 11714 8192000000 10537682288 11002314752 1172821632 12.523 100 44 83 1 1641135840 457472720 10841488
06:05:12 18237 8192000000 10488082512 10990915584 1162639312 12.428 100 36 71 0 1580320576 408326384 9354880
06:06:31 31345 8192000000 10364792912 10863992832 1149976032 12.309 100 26 52 0 1471503936 314893840 6634064
06:08:14 53521 8192000000 10162647008 10685018112 1091684544 11.759 95 16 32 0 1257913344 162713328 3515472
06:10:12 17657 8192000000 10084919536 10526543872 1148435456 12.295 100 62 109 9 1198417920 35788720 14193744
06:11:36 148739 8192000000 10164969856 10659422208 1153093728 12.339 100 42 81 1 1244543760 82676688 8773344
06:15:15 44346 8192000000 10126763360 10570952704 1136496784 12.183 99 31 60 0 1210520816 68252880 5771152
06:17:01 55660 8192000000 10165317824 10609930240 1157980208 12.384 100 32 64 0 1279938416 114247008 7711200
06:19:03 42384 8192000000 10135673088 10676699136 1153083376 12.338 100 12 22 0 1255645904 99747712 2814816
06:20:55 92770 8192000000 10111521792 10556837888 1098816736 11.826 95 23 45 0 1159981936 56239232 4925968
06:23:28 67697 8192000000 10125963840 10592604160 1138494768 12.201 99 13 26 0 1206975904 65602480 2878656
06:25:48 65045 8192000000 10120610240 10578694144 1119337120 12.021 97 26 50 0 1205798784 80902880 5558784
06:28:01 69876 8192000000 10164664992 10623348736 1149688576 12.307 99 24 46 0 1251951776 97119808 5143392
06:30:24 53921 8192000000 10192935920 10649899008 1145335312 12.266 99 23 43 0 1283925792 133663824 4926656
06:32:27 26773 8192000000 10204031312 10672918528 1141226368 12.227 99 33 66 0 1315867616 166669536 7971712
06:34:00 59357 8192000000 10090071968 10624159744 1105473632 11.890 97 25 49 0 1223459120 112345392 5640096
06:36:04 42152 8192000000 10107344768 10529681408 1118291408 12.011 99 45 90 0 1171191744 44200352 8699984
06:37:51 64645 8192000000 10105769152 10561421312 1142912688 12.243 100 50 93 1 1200714608 48875024 8926896
06:39:58 24995 8192000000 10130037008 10601172992 1153829696 12.345 100 23 46 0 1243675904 84886224 4959984
06:41:39 60715 8192000000 10124874736 10628702208 1156072128 12.366 100 11 21 0 1229621328 71596352 1952848
06:43:40 91415 8192000000 10046818400 10510004224 1101221552 11.849 96 7 13 1 1144526720 42199728 1105440
06:46:15 24931 8192000000 10107272064 10547122176 1085471328 11.700 95 27 54 0 1154242368 62199248 6571792
06:47:46 43611 8192000000 10137262784 10574589952 1121552560 12.042 99 24 50 0 1229065392 102025696 5487136
06:49:30 30236 8192000000 10196239296 10633977856 1131543600 12.136 99 29 58 0 1278285680 139975040 6767040
06:51:15 26483 8192000000 10268089120 10721316864 1156087168 12.367 100 38 73 0 1345861312 181374464 8399680
06:52:47 18714 8192000000 10252521440 10707812352 1119260288 12.020 98 33 64 0 1330162448 203483584 7418576
06:54:06 25755 8192000000 10332415200 10786197504 1155317056 12.359 100 55 108 0 1429481808 262120400 12044352
06:55:38 21271 8192000000 10262004832 10740350976 1142231680 12.237 100 31 62 0 1390931104 241221264 7478160
06:57:24 17115 8192000000 10151992208 10710876160 1127532720 12.098 100 31 62 0 1251764704 117489808 6742176
06:58:44 43160 8192000000 10074755712 10534465536 1117916064 12.007 99 47 94 0 1186455616 58318656 10220896
07:00:31 85817 8192000000 10044030784 10510888960 1098806016 11.826 98 15 30 0 1138079968 36232144 3041808
07:02:57 73631 8192000000 10077369136 10534068224 1126813424 12.091 100 18 35 0 1171034848 40418368 3803056
07:05:12 56575 8192000000 10097234512 10538151936 1119815456 12.025 99 18 38 0 1166239776 42630496 3793824
07:07:09 71992 8192000000 10052274640 10515292160 1088237504 11.726 96 23 46 0 1143500240 50749888 4512848
07:09:28 82941 8192000000 10053088800 10537734144 1117125696 12.000 98 15 30 0 1191007248 70939856 2941696
07:11:52 22360 8192000000 10084647360 10517053440 1119355760 12.021 99 43 86 1 1165203184 37026336 8821088
07:13:17 18076 8192000000 10125097344 10545508352 1132684000 12.147 100 57 108 0 1205079248 61243296 11151952
07:14:38 8903 8192000000 10087455328 10519724032 1136828896 12.186 100 38 72 0 1187735184 43310176 7596112
07:15:48 48605 8192000000 10083200928 10548174848 1112651264 11.958 98 24 48 0 1174699120 57355088 4692768
07:17:38 93949 8192000000 10087753744 10563751936 1134755824 12.166 100 23 44 0 1205422592 65962256 4704512
07:20:21 35379 8192000000 10079270608 10564988928 1111841280 11.950 98 19 37 0 1171060816 55748800 3470736
07:21:57 71240 8192000000 10095305968 10577928192 1119720752 12.024 99 14 29 1 1197107680 74520448 2866480
07:24:09 55033 8192000000 10073814160 10575286272 1122547568 12.051 99 10 18 1 1176865616 52858208 1459840
07:26:05 97999 8192000000 10065506912 10547367936 1087485104 11.719 96 11 21 0 1126967488 36896960 2585424
07:28:43 38638 8192000000 10156012768 10596651008 1133426560 12.154 99 25 50 0 1215295472 76040592 5828320
07:30:26 34633 8192000000 10170427472 10635931648 1127651808 12.099 99 33 67 0 1259688704 124597952 7438944
07:32:05 59299 8192000000 10178029280 10646867968 1103907808 11.875 97 27 54 0 1265141024 154670448 6562768
07:34:10 21053 8192000000 10198758320 10628268032 1110801152 11.940 98 46 92 0 1280056768 159091376 10164240
07:35:32 41262 8192000000 10068123232 10497384448 1091886480 11.761 98 31 62 0 1191396928 93317424 6193024
07:37:16 23800 8192000000 9119698384 9399177216 250743264 2.969 23 3 4 0 272255408 21284112 228032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-01-2023 07:38:34 to 21-01-2023 07:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-01-2023 07:38:51 to 21-01-2023 07:38:53.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          214966
MON$OLDEST_ACTIVE               214967
MON$OLDEST_SNAPSHOT             214967
MON$NEXT_TRANSACTION            214967
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-30 07:30:08.6030 GMT
MON$PAGES                       2055456
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:920: 
:921: mscs-test-161.budzdorov.ru    Sat Jan 21 07:37:49 2023
:922:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:923: 
--- end of diff output ---

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

21.01.23 07:38:54. There are 3 logs in /var/tmp/logs-oltp40 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
2023-01-21 07:38:57 No severe PSQL-related problems occured
21.01.23 07:38:58. End of report.