Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2021 13: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 16198204 kB
MemAvailable 31373584 kB
Buffers 0 kB
Cached 15443848 kB
SwapCached 444 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.1
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 2021-12-22 13:37:21
DTS_END                         2021-12-22 13:37:09.7060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43140
TRN_ID                          231949
ATT_ID                          259
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: 9369.77 26412 1124372
customer order: creation 163.89 441 19667
customer order: refuse 14.51 364 1741
order to supplier: creation 45.54 1406 5465
order to supplier: removal 3.51 131611 421
invoice (draft): creation 46.03 3541 5523
invoice (draft): removal 53.38 1014 6405
invoice accept: apply 41.63 17714 4996
invoice accept: cancel 47.81 1043 5737
customer reserve: creation 3898.29 169 467795
customer reserve: removal 4761.63 76 571395
realization accept: apply 82.24 204 9869
realization accept: cancel 85.72 130 10286
payment from customer: creation 57.03 133 6844
payment from customer: removal 5.93 116 711
payment to supplier: creation 48.48 134 5817
payment to supplier: removal 3.75 86 450
service: total inventory turnovers 3.52 4608 422
service: total monetary turnovers 6.05 547 726
service: aggregate perf. data 0.82 17713 98
service: refresh index statistics 0.03 347190 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2021 13:37:47 to 22-12-2021 13:37:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4463
TEST_TIME 2 4495
TEST_TIME 3 4268
TEST_TIME 4 4373
TEST_TIME 5 5051
TEST_TIME 6 5112
TEST_TIME 7 5471
TEST_TIME 8 6416
TEST_TIME 9 6345
TEST_TIME 10 6118
TEST_TIME 11 6762
TEST_TIME 12 6977
TEST_TIME 13 7059
TEST_TIME 14 7392
TEST_TIME 15 7501
TEST_TIME 16 7493
TEST_TIME 17 7881
TEST_TIME 18 8205
TEST_TIME 19 8229
TEST_TIME 20 8335
TEST_TIME 21 8479
TEST_TIME 22 8295
TEST_TIME 23 8580
TEST_TIME 24 8947
TEST_TIME 25 8969
TEST_TIME 26 9082
TEST_TIME 27 8996
TEST_TIME 28 9301
TEST_TIME 29 9674
TEST_TIME 30 10198
TEST_TIME 31 11256
TEST_TIME 32 11157
TEST_TIME 33 11212
TEST_TIME 34 11253
TEST_TIME 35 10989
TEST_TIME 36 10824
TEST_TIME 37 10800
TEST_TIME 38 10802
TEST_TIME 39 10731
TEST_TIME 40 10569
TEST_TIME 41 10434
TEST_TIME 42 10607
TEST_TIME 43 10472
TEST_TIME 44 10576
TEST_TIME 45 10640
TEST_TIME 46 10546
TEST_TIME 47 10501
TEST_TIME 48 10532
TEST_TIME 49 10534
TEST_TIME 50 10452
TEST_TIME 51 10308
TEST_TIME 52 10208
TEST_TIME 53 10156
TEST_TIME 54 10127
TEST_TIME 55 10027
TEST_TIME 56 10000
TEST_TIME 57 10006
TEST_TIME 58 9923
TEST_TIME 59 9910
TEST_TIME 60 9904
TEST_TIME 61 9783
TEST_TIME 62 9724
TEST_TIME 63 9633
TEST_TIME 64 9550
TEST_TIME 65 9839
TEST_TIME 66 9878
TEST_TIME 67 9918
TEST_TIME 68 9953
TEST_TIME 69 9935
TEST_TIME 70 9929
TEST_TIME 71 9911
TEST_TIME 72 9932
TEST_TIME 73 9936
TEST_TIME 74 9920
TEST_TIME 75 9893
TEST_TIME 76 9935
TEST_TIME 77 9940
TEST_TIME 78 9860
TEST_TIME 79 9775
TEST_TIME 80 9770
TEST_TIME 81 9701
TEST_TIME 82 9713
TEST_TIME 83 9676
TEST_TIME 84 9626
TEST_TIME 85 9608
TEST_TIME 86 9565
TEST_TIME 87 9493
TEST_TIME 88 9448
TEST_TIME 89 9429
TEST_TIME 90 9398
TEST_TIME 91 9375
TEST_TIME 92 9407
TEST_TIME 93 9453
TEST_TIME 94 9463
TEST_TIME 95 9422
TEST_TIME 96 9438
TEST_TIME 97 9483
TEST_TIME 98 9492
TEST_TIME 99 9498
TEST_TIME 100 9515
TEST_TIME 101 9569
TEST_TIME 102 9574
TEST_TIME 103 9543
TEST_TIME 104 9509
TEST_TIME 105 9506
TEST_TIME 106 9454
TEST_TIME 107 9421
TEST_TIME 108 9377
TEST_TIME 109 9393
TEST_TIME 110 9364
TEST_TIME 111 9331
TEST_TIME 112 9430
TEST_TIME 113 9390
TEST_TIME 114 9398
TEST_TIME 115 9376
TEST_TIME 116 9364
TEST_TIME 117 9342
TEST_TIME 118 9476
TEST_TIME 119 9446
TEST_TIME 120 9373

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-12-2021 13:37:50 to 22-12-2021 13:37:54.

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 1556588 1556588 0 0.00 0 16888 7 0 0
doc_list_biud 1556588 1556588 0 0.00 0 64430 35 0 0
sp_add_invoice_to_stock 5069 4996 73 1.44 30 164208 17714 0 73
sp_cancel_adding_invoice 5737 5737 0 0.00 5 93802 1044 0 0
sp_cancel_client_order 1741 1741 0 0.00 4 12128 365 0 0
sp_cancel_customer_reserve 571395 571395 0 0.00 1 30129 76 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 11884 117 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 3 2083 87 0 0
sp_cancel_supplier_invoice 6408 6405 3 0.04 2 64494 1014 0 3
sp_cancel_supplier_order 421 421 0 0.00 10 1279342 131612 0 0
sp_cancel_write_off 10286 10286 0 0.00 1 12375 130 0 0
sp_client_order 19667 19667 0 0.00 4 32968 442 0 0
sp_customer_reserve 467795 467795 0 0.00 0 160485 170 0 0
sp_fill_shopping_cart 30638 30638 0 0.00 1 14980 46 0 0
sp_fill_shopping_cart_clo_res 467790 467790 0 0.00 0 20257 17 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 29956 362 0 0
sp_kill_cost_storno 17184 17184 0 0.00 0 6849 71 0 0
sp_kill_qstorno_ret_qs2qd 1159902 1159902 0 0.00 0 64428 41 0 0
sp_kill_qty_storno 610844 610844 0 0.00 0 64429 88 0 0
sp_lock_selected_doc 20637 20637 0 0.00 0 10259 6 0 0
sp_make_cost_storno 27520 27520 0 0.00 0 13607 62 0 0
sp_multiply_rows_for_pdistr 27520 27520 0 0.00 0 10398 35 0 0
sp_multiply_rows_for_qdistr 19667 19667 0 0.00 0 32944 238 0 0
sp_pay_from_customer 6844 6844 0 0.00 4 21757 133 0 0
sp_pay_to_supplier 5817 5817 0 0.00 5 16674 134 0 0
sp_payment_common 12661 12661 0 0.00 1 10155 30 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 0 12123 337 0 0
sp_qd_handle_on_invoice_upd_sts 10727 10727 0 0.00 0 19804 431 0 0
sp_qd_handle_on_reserve_upd_sts 9869 9869 0 0.00 0 30611 64 0 0
sp_reserve_write_off 9869 9869 0 0.00 7 30621 204 0 0
sp_supplier_invoice 5523 5523 0 0.00 7 75187 3542 0 0
sp_supplier_order 5465 5465 0 0.00 5 32022 1407 0 0
srv_aggregate_perf_data 98 98 0 0.00 2380 210999 17713 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1454 185117 48401 0 0
srv_find_qd_qs_mism 1076815 1076815 0 0.00 0 16887 5 0 0
srv_make_invnt_saldo 422 422 0 0.00 72 94354 4608 0 0
srv_make_money_saldo 726 726 0 0.00 1 14332 548 0 0
srv_recalc_idx_stat 4 4 0 0.00 232477 512379 347191 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4996 4996 0 0.00 0 15750 193 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 1670 78 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 4291 14 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 845 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 1612 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 629 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 980 18 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 30348 2072 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 547 3 0 0
t$perf-norm:sp_client_order 19667 19667 0 0.00 0 5716 21 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 2634 13 0 0
t$perf-norm:sp_pay_from_customer 6844 6844 0 0.00 0 5853 10 0 0
t$perf-norm:sp_pay_to_supplier 5817 5817 0 0.00 0 4530 10 0 0
t$perf-norm:sp_reserve_write_off 9869 9869 0 0.00 0 5027 12 0 0
t$perf-norm:sp_supplier_invoice 5523 5523 0 0.00 0 5506 23 0 0
t$perf-norm:sp_supplier_order 5465 5465 0 0.00 0 7266 22 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 376 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6452 129 0 0
t$perf-norm:srv_make_invnt_saldo 422 422 0 0.00 0 3129 16 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 887 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 5 0 0
v_add_invoice_to_stock 10120 9974 146 1.44 0 4385 17 0 146
v_all_customers 35760 35760 0 0.00 0 22 1 0 0
v_all_suppliers 30700 30700 0 0.00 0 102 1 0 0
v_all_wares 39334 39334 0 0.00 0 89 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 113 5 0 0
v_cancel_client_order 3482 3482 0 0.00 0 3775 6 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 5169 12 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 2760 8 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 1215 8 0 0
v_cancel_supplier_invoice 818 812 6 0.73 0 2981 20 0 6
v_cancel_supplier_order 842 842 0 0.00 0 1836 9 0 0
v_cancel_write_off 1912 1912 0 0.00 0 7141 22 0 0
v_max_id_clo_ord 5458 5458 0 0.00 0 4400 3 0 0
v_max_id_clo_res 975 975 0 0.00 0 1560 5 0 0
v_max_id_ord_sup 5513 5513 0 0.00 0 3634 3 0 0
v_max_non_paid_invoice 1438 1438 0 0.00 0 1134 3 0 0
v_max_non_paid_realizn 3657 3657 0 0.00 0 1721 3 0 0
v_min_id_clo_ord 5458 5458 0 0.00 0 11796 11 0 0
v_min_id_clo_res 975 975 0 0.00 0 3938 7 0 0
v_min_id_ord_sup 5513 5513 0 0.00 0 6867 9 0 0
v_min_non_paid_invoice 5817 5817 0 0.00 0 7724 19 0 0
v_min_non_paid_realizn 6844 6844 0 0.00 0 10010 24 0 0
v_our_firm 9948 9948 0 0.00 0 101 1 0 0
v_reserve_write_off 19738 19738 0 0.00 0 4129 6 0 0
x_lock_depdocs_on_canc_invoice 11736 11736 0 0.00 0 16435 138 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 3867 370 0 0
x_make_qstorno_1000_1200 5465 5465 0 0.00 2 31996 1312 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 3 73218 3382 0 0
x_make_qstorno_2100_3300 467795 467795 0 0.00 0 160483 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2021 13:37:55 to 22-12-2021 13:37:59.

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
11:07:13 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 22576 8192000000 9743279136 10045345792 822495776 9.124 100 86 172 0 850004240 7438912 20069552
11:09:41 19091 8192000000 9772977664 10083352576 847519952 9.375 100 88 173 1 879792496 12274560 19997984
11:11:06 19391 8192000000 9798277152 10117136384 875379120 9.654 100 83 166 0 913011280 19008576 18623584
11:12:32 19897 8192000000 9862562352 10194198528 911258272 10.010 100 82 164 0 975097328 45824288 18014768
11:13:55 10546 8192000000 9910100352 10251100160 937281136 10.266 100 82 163 1 1022553584 67720848 17551600
11:15:08 11312 8192000000 9946271680 10296266752 969079312 10.578 100 81 161 0 1058945664 73215056 16651296
11:16:24 3955 8192000000 9975513072 10335805440 999800960 10.877 100 78 155 1 1090311168 74821776 15688432
11:17:31 6908 8192000000 9991221408 10356617216 1025008608 11.120 100 77 152 1 1095529488 55833936 14686944
11:18:39 1682 8192000000 10025324432 10396532736 1059778800 11.454 100 86 172 3 1138429664 61372240 17278624
11:19:42 28936 8192000000 9992434256 10371768320 1066418512 11.518 100 47 94 0 1102248880 25121008 10709360
11:21:12 3961 8192000000 9984504352 10375528448 1078448896 11.633 100 51 104 1 1104986368 14081808 12455664
11:22:17 6610 8192000000 9993239888 10390294528 1086247568 11.707 100 37 72 0 1114498032 21067968 7182496
11:23:24 5331 8192000000 10011515744 10408427520 1091806048 11.760 100 39 78 1 1116897728 16547552 8544128
11:24:31 52739 8192000000 10034317408 10440945664 1093159856 11.773 100 41 81 2 1123308752 21280032 8868864
11:26:24 6336 8192000000 10027162992 10431291392 1097638816 11.815 100 44 88 2 1140800576 33359040 9802720
11:27:34 8977 8192000000 9999386480 10403110912 1071639152 11.568 98 35 70 1 1105684544 26571408 7473984
11:28:43 8777 8192000000 9984968896 10394943488 1051276592 11.373 97 38 76 1 1076023616 16043408 8703616
11:29:54 27924 8192000000 9964186880 10350231552 1032181056 11.189 97 54 111 2 1065000656 21093792 11725808
11:31:25 6650 8192000000 9931453952 10316210176 998289600 10.862 97 39 78 0 1036897632 30430896 8177136
11:32:34 7470 8192000000 9951253584 10335715328 1028785696 11.157 100 41 83 1 1061073904 23064432 9223776
11:33:44 9333 8192000000 9952359136 10344591360 1023842992 11.109 98 33 67 0 1053935696 22163504 7929200
11:34:54 32471 8192000000 9970961024 10356654080 1046652608 11.329 100 44 86 1 1082102224 26364544 9085072
11:36:27 7060 8192000000 10000559440 10397458432 1063765152 11.493 100 55 110 0 1101546832 25326688 12454992
11:37:39 40140 8192000000 10018066528 10412122112 1080871088 11.656 100 41 82 2 1113902832 24076448 8955296
11:39:22 25512 8192000000 10058095808 10456158208 1098316800 11.822 100 33 66 0 1167071456 61364224 7390432
11:40:52 24800 8192000000 10104107792 10499227648 1110122640 11.934 100 48 96 0 1203293168 81753200 11417328
11:42:18 38201 8192000000 10099438048 10515673088 1115465760 11.984 100 30 60 1 1220035968 97292288 7277920
11:44:01 1844 8192000000 10146969040 10562064384 1121553680 12.042 100 72 144 1 1257690112 119946496 16189936
11:45:08 11720 8192000000 10156473632 10573905920 1137348960 12.191 100 55 108 1 1284897168 135015984 12532224
11:46:28 11343 8192000000 10151001808 10567749632 1147028512 12.282 100 53 104 1 1259329344 101284048 11016784
11:47:44 5273 8192000000 10217819696 10620727296 1165530096 12.455 100 73 146 0 1325996064 144387840 16078128
11:49:00 4062 8192000000 10261250000 10673786880 1179147248 12.582 100 67 133 0 1372968352 178986224 14834880
11:50:09 9265 8192000000 10308755776 10722349056 1188264800 12.667 100 77 154 2 1404863888 199560128 17038960
11:51:27 2353 8192000000 10301503856 10733375488 1184148304 12.629 100 72 144 0 1400313632 199919024 16246304
11:52:37 3633 8192000000 10343605152 10750631936 1181246368 12.602 100 81 162 0 1446635376 247660464 17728544
11:53:46 2076 8192000000 10365617600 10786361344 1186368224 12.650 100 81 154 1 1477464656 272717856 18378576
11:54:52 1453 8192000000 10379767280 10793070592 1189351536 12.677 100 93 185 1 1478542832 268250496 20940800
11:55:58 6700 8192000000 10380747936 10803589120 1160119440 12.404 97 55 110 0 1474413312 301394640 12899232
11:57:15 2781 8192000000 10415769744 10824757248 1159548448 12.399 100 59 117 0 1511325008 337346368 14430192
11:58:27 3626 8192000000 10428011168 10840723456 1149203408 12.302 100 72 144 0 1531147888 364651376 17293104
11:59:40 3651 8192000000 10443817632 10854989824 1150077440 12.310 100 64 128 0 1543825632 378259840 15488352
12:00:54 1648 8192000000 10491719040 10930208768 1158920032 12.393 100 92 181 0 1585111968 404682896 21509040
12:01:58 7963 8192000000 10452924992 10895958016 1146146848 12.273 99 48 96 0 1539092784 380635616 12310320
12:03:14 11212 8192000000 10479909344 10948263936 1159278736 12.397 100 35 70 0 1589874096 421228896 9366464
12:04:47 6298 8192000000 10524018880 10937114624 1148019216 12.291 100 71 140 2 1617193712 451677296 17497200
12:05:57 7054 8192000000 10501925232 10946842624 1139498400 12.211 100 30 58 1 1599545072 451823712 8222960
12:07:10 15294 8192000000 10458821456 10956980224 1134639008 12.165 100 29 59 0 1565848096 423349968 7859120
12:08:31 24135 8192000000 10179175984 10725511168 1121140832 12.038 99 21 41 2 1373147936 245978528 6028576
12:09:57 43620 8192000000 10098061248 10573189120 1129492560 12.117 100 24 47 1 1222388112 87424272 5471280
12:12:06 71578 8192000000 10078081680 10515976192 1109038176 11.923 98 25 50 1 1133020592 19083872 4898544
12:14:19 136606 8192000000 10077384544 10562342912 1093253200 11.774 97 20 40 0 1166544304 69026160 4264944
12:17:36 95654 8192000000 10115902896 10564702208 1120425536 12.031 98 18 36 1 1188969360 64680288 3863536
12:20:32 121588 8192000000 10101180480 10603769856 1092588432 11.767 95 12 24 0 1171869952 76693808 2587712
12:23:34 31670 8192000000 10069728768 10507788288 1119177936 12.019 98 18 35 0 1160432704 37602720 3652048
12:25:12 94030 8192000000 10115020800 10573524992 1141733376 12.232 99 13 26 0 1195847488 51302176 2811936
12:27:53 86310 8192000000 10085282624 10527174656 1137504656 12.192 99 25 50 1 1162061824 18872000 5685168
12:30:24 63328 8192000000 10097615520 10541187072 1158268864 12.387 100 47 91 1 1197021104 28892352 9859888
12:32:35 32939 8192000000 10086112480 10532339712 1143136336 12.245 99 22 42 1 1176528880 28736208 4656336
12:34:09 18544 8192000000 10114209760 10560487424 1153651888 12.344 100 26 52 0 1213903152 54299264 5952000
12:35:28 23442 8192000000 10128427136 10563313664 1141660624 12.231 98 56 113 0 1215962656 61577536 12724496
12:36:58 185117 8192000000 10195051872 10664230912 1109373936 11.926 96 11 22 0 1179832912 67986192 2472784
12:41:03 78105 8192000000 10241407168 10719789056 1136925872 12.187 98 24 48 1 1344252656 201796384 5530400
12:43:47 96906 8192000000 10070509456 10547589120 1109096000 11.924 97 11 23 1 1187909552 76338848 2474704
12:46:24 80566 8192000000 10066692736 10535849984 1070221072 11.554 94 24 48 0 1111969856 37074000 4674784
12:48:45 91015 8192000000 10070204400 10541969408 1109681360 11.929 97 20 40 0 1156925920 43598432 3646128
12:51:17 14017 8192000000 10076863296 10513981440 1116949792 11.998 99 51 102 1 1158555200 30775744 10829664
12:52:37 104871 8192000000 10040462368 10494103552 1037127824 11.237 93 14 28 0 1092178416 52183696 2866896
12:55:23 117801 8192000000 10085320928 10529329152 1099885504 11.837 99 39 78 0 1183798752 75108192 8805056
12:58:34 73659 8192000000 10031833984 10474418176 1084664416 11.692 97 39 77 0 1139799232 46336416 8798400
13:00:53 39475 8192000000 10038964656 10488307712 1084745328 11.693 98 8 16 0 1123782016 37313872 1722816
13:02:36 64210 8192000000 10030451744 10490134528 1107484736 11.909 99 12 24 0 1145152848 35308400 2359712
13:04:42 66852 8192000000 10046316272 10517766144 1118086976 12.009 100 16 32 0 1148365648 27067296 3211376
13:06:50 40946 8192000000 10046995520 10493956096 1116171520 11.991 99 18 35 2 1135601728 16006720 3423488
13:08:32 75022 8192000000 10077545648 10540609536 1077264448 11.621 95 19 38 0 1160318368 78857344 4196576
13:10:52 45257 8192000000 10073664608 10524770304 1097657056 11.815 98 17 34 1 1142184720 40694912 3832752
13:12:42 75794 8192000000 10128205024 10608336896 1128961584 12.112 99 26 52 0 1238511184 104018496 5531104
13:15:07 30566 8192000000 10089499536 10527277056 1139027232 12.206 100 29 57 0 1192911856 48178624 5706000
13:16:41 72525 8192000000 10138067504 10605232128 1079101360 11.639 94 13 26 0 1173278352 91665664 2511328
13:18:59 139253 8192000000 10095051184 10552471552 1124816672 12.072 99 18 36 1 1186869152 58717104 3335376
13:22:19 58740 8192000000 10063687360 10572001280 1124013200 12.065 99 5 10 0 1163298624 38376832 908592
13:24:19 78197 8192000000 10076313392 10530557952 1124037664 12.065 98 15 30 0 1159270720 32066720 3166336
13:26:37 46321 8192000000 10083815696 10530652160 1129359824 12.115 99 16 32 0 1169231616 36542384 3329408
13:28:38 49805 8192000000 10166942288 10619682816 1108684528 11.920 96 14 28 0 1207025376 95120928 3219920
13:30:29 66575 8192000000 10084486688 10543063040 1104416048 11.880 97 28 56 1 1146953216 36472144 6065024
13:32:38 72739 8192000000 10150837296 10588954624 1125504960 12.079 98 35 71 1 1217358432 84669360 7184112
13:35:09 93431 8192000000 10054165600 10489528320 1068011216 11.533 94 17 34 2 1112881232 41286496 3583520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2021 13:38:01 to 22-12-2021 13:38:16.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2021 13:38:17 to 22-12-2021 13:38:19.

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          239276
MON$OLDEST_ACTIVE               239277
MON$OLDEST_SNAPSHOT             239277
MON$NEXT_TRANSACTION            239277
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                       2077920
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2694 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2694 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2694 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2694 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 ---
--- end of diff output ---

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

22.12.21 13:38:20. 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
2021-12-22 13:38:22 No severe PSQL-related problems occured
22.12.21 13:38:23. End of report.