Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2021 10: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 16324320 kB
MemAvailable 31378812 kB
Buffers 0 kB
Cached 15335832 kB
SwapCached 440 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-24 10:37:32
DTS_END                         2021-12-24 10:37:10.5400
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/44682
TRN_ID                          239398
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: 9296.78 19080 1115613
customer order: creation 178.01 388 21361
customer order: refuse 14.80 332 1776
order to supplier: creation 46.28 1410 5554
order to supplier: removal 3.50 104820 420
invoice (draft): creation 46.58 3374 5590
invoice (draft): removal 52.83 864 6340
invoice accept: apply 42.68 17244 5121
invoice accept: cancel 48.07 850 5768
customer reserve: creation 3862.32 173 463478
customer reserve: removal 4700.56 62 564067
realization accept: apply 87.67 165 10520
realization accept: cancel 81.69 120 9803
payment from customer: creation 61.25 107 7350
payment from customer: removal 5.82 89 698
payment to supplier: creation 49.66 110 5959
payment to supplier: removal 3.60 60 432
service: total inventory turnovers 4.08 3100 490
service: total monetary turnovers 6.42 481 770
service: aggregate perf. data 0.93 13914 111
service: refresh index statistics 0.04 233946 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2021 10:38:07 to 24-12-2021 10:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7510
TEST_TIME 2 6564
TEST_TIME 3 5639
TEST_TIME 4 5531
TEST_TIME 5 5605
TEST_TIME 6 5402
TEST_TIME 7 5682
TEST_TIME 8 5933
TEST_TIME 9 5789
TEST_TIME 10 6178
TEST_TIME 11 6502
TEST_TIME 12 6555
TEST_TIME 13 7012
TEST_TIME 14 7585
TEST_TIME 15 7513
TEST_TIME 16 7539
TEST_TIME 17 7598
TEST_TIME 18 8002
TEST_TIME 19 8223
TEST_TIME 20 8529
TEST_TIME 21 8682
TEST_TIME 22 8981
TEST_TIME 23 8844
TEST_TIME 24 9283
TEST_TIME 25 9890
TEST_TIME 26 9968
TEST_TIME 27 10229
TEST_TIME 28 11196
TEST_TIME 29 11167
TEST_TIME 30 10936
TEST_TIME 31 11009
TEST_TIME 32 10872
TEST_TIME 33 10952
TEST_TIME 34 10896
TEST_TIME 35 10849
TEST_TIME 36 10632
TEST_TIME 37 10361
TEST_TIME 38 10116
TEST_TIME 39 10190
TEST_TIME 40 10121
TEST_TIME 41 10117
TEST_TIME 42 9994
TEST_TIME 43 9857
TEST_TIME 44 9816
TEST_TIME 45 9725
TEST_TIME 46 9834
TEST_TIME 47 9896
TEST_TIME 48 9803
TEST_TIME 49 9672
TEST_TIME 50 9838
TEST_TIME 51 9885
TEST_TIME 52 9867
TEST_TIME 53 9871
TEST_TIME 54 9836
TEST_TIME 55 9832
TEST_TIME 56 10010
TEST_TIME 57 9965
TEST_TIME 58 9880
TEST_TIME 59 9933
TEST_TIME 60 9929
TEST_TIME 61 9942
TEST_TIME 62 9823
TEST_TIME 63 9739
TEST_TIME 64 9665
TEST_TIME 65 9674
TEST_TIME 66 9607
TEST_TIME 67 9496
TEST_TIME 68 9435
TEST_TIME 69 9408
TEST_TIME 70 9387
TEST_TIME 71 9376
TEST_TIME 72 9352
TEST_TIME 73 9341
TEST_TIME 74 9463
TEST_TIME 75 9446
TEST_TIME 76 9451
TEST_TIME 77 9421
TEST_TIME 78 9533
TEST_TIME 79 9548
TEST_TIME 80 9572
TEST_TIME 81 9555
TEST_TIME 82 9463
TEST_TIME 83 9412
TEST_TIME 84 9435
TEST_TIME 85 9366
TEST_TIME 86 9292
TEST_TIME 87 9236
TEST_TIME 88 9324
TEST_TIME 89 9348
TEST_TIME 90 9360
TEST_TIME 91 9345
TEST_TIME 92 9340
TEST_TIME 93 9336
TEST_TIME 94 9375
TEST_TIME 95 9354
TEST_TIME 96 9416
TEST_TIME 97 9414
TEST_TIME 98 9447
TEST_TIME 99 9466
TEST_TIME 100 9505
TEST_TIME 101 9444
TEST_TIME 102 9370
TEST_TIME 103 9354
TEST_TIME 104 9334
TEST_TIME 105 9271
TEST_TIME 106 9275
TEST_TIME 107 9234
TEST_TIME 108 9255
TEST_TIME 109 9220
TEST_TIME 110 9239
TEST_TIME 111 9237
TEST_TIME 112 9241
TEST_TIME 113 9215
TEST_TIME 114 9275
TEST_TIME 115 9301
TEST_TIME 116 9300
TEST_TIME 117 9370
TEST_TIME 118 9346
TEST_TIME 119 9371
TEST_TIME 120 9300

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2021 10:38:11 to 24-12-2021 10:38:14.

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 1546030 1546030 0 0.00 0 16808 7 0 0
doc_list_biud 1546029 1546029 0 0.00 0 84914 29 0 0
sp_add_invoice_to_stock 5277 5121 156 2.95 53 167602 17245 0 156
sp_cancel_adding_invoice 5768 5768 0 0.00 5 111188 851 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 15478 333 0 0
sp_cancel_customer_reserve 564067 564067 0 0.00 1 84916 62 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 7372 90 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 1599 61 0 0
sp_cancel_supplier_invoice 6349 6340 9 0.14 1 16251 865 0 9
sp_cancel_supplier_order 420 420 0 0.00 5 1060754 104821 0 0
sp_cancel_write_off 9803 9803 0 0.00 1 10602 121 0 0
sp_client_order 21361 21361 0 0.00 4 73447 389 0 0
sp_customer_reserve 463478 463478 0 0.00 0 57746 174 0 0
sp_fill_shopping_cart 32481 32481 0 0.00 1 9240 41 0 0
sp_fill_shopping_cart_clo_res 463464 463464 0 0.00 0 14122 14 0 0
sp_get_clo_for_invoice 5107 5107 0 0.00 0 31512 350 0 0
sp_kill_cost_storno 16701 16701 0 0.00 0 10880 55 0 0
sp_kill_qstorno_ret_qs2qd 1144697 1144697 0 0.00 0 84911 34 0 0
sp_kill_qty_storno 603800 603800 0 0.00 0 84914 72 0 0
sp_lock_selected_doc 21496 21496 0 0.00 0 10968 6 0 0
sp_make_cost_storno 28936 28936 0 0.00 0 11967 50 0 0
sp_multiply_rows_for_pdistr 28936 28936 0 0.00 0 12239 32 0 0
sp_multiply_rows_for_qdistr 21361 21361 0 0.00 0 73438 206 0 0
sp_pay_from_customer 7350 7350 0 0.00 4 12451 108 0 0
sp_pay_to_supplier 5959 5959 0 0.00 5 12083 110 0 0
sp_payment_common 13308 13308 0 0.00 1 11881 24 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 12390 297 0 0
sp_qd_handle_on_invoice_upd_sts 10874 10874 0 0.00 0 14647 339 0 0
sp_qd_handle_on_reserve_upd_sts 10520 10520 0 0.00 0 15498 48 0 0
sp_reserve_write_off 10520 10520 0 0.00 6 17866 165 0 0
sp_supplier_invoice 5590 5590 0 0.00 6 140709 3375 0 0
sp_supplier_order 5554 5554 0 0.00 6 44141 1411 0 0
srv_aggregate_perf_data 111 111 0 0.00 2686 135285 13914 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1540 128861 43556 0 0
srv_find_qd_qs_mism 1067710 1067710 0 0.00 0 16790 6 0 0
srv_make_invnt_saldo 490 490 0 0.00 27 114888 3101 0 0
srv_make_money_saldo 770 770 0 0.00 1 11267 481 0 0
srv_recalc_idx_stat 5 5 0 0.00 112586 327377 233947 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5121 5121 0 0.00 0 14825 167 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 5568 108 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 7814 17 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 1534 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 1952 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 328 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1158 11 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 42727 1683 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 1398 7 0 0
t$perf-norm:sp_client_order 21361 21361 0 0.00 0 11331 18 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 674 5 0 0
t$perf-norm:sp_pay_from_customer 7350 7350 0 0.00 0 21276 14 0 0
t$perf-norm:sp_pay_to_supplier 5959 5959 0 0.00 0 7296 11 0 0
t$perf-norm:sp_reserve_write_off 10520 10520 0 0.00 0 17962 15 0 0
t$perf-norm:sp_supplier_invoice 5590 5590 0 0.00 0 10188 23 0 0
t$perf-norm:sp_supplier_order 5554 5554 0 0.00 0 6446 14 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 652 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1608 27 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 976 10 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 1324 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10508 10196 312 2.96 0 6956 17 0 312
v_all_customers 38838 38838 0 0.00 0 145 1 0 0
v_all_suppliers 30676 30676 0 0.00 0 92 1 0 0
v_all_wares 42716 42716 0 0.00 0 176 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1753 9 0 0
v_cancel_client_order 3552 3552 0 0.00 0 2939 5 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 2890 7 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 891 4 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 1590 6 0 0
v_cancel_supplier_invoice 862 844 18 2.08 0 2118 16 0 18
v_cancel_supplier_order 840 840 0 0.00 0 4607 9 0 0
v_cancel_write_off 2066 2066 0 0.00 0 1493 12 0 0
v_max_id_clo_ord 5544 5544 0 0.00 0 558 1 0 0
v_max_id_clo_res 972 972 0 0.00 0 3495 19 0 0
v_max_id_ord_sup 5577 5577 0 0.00 0 6850 8 0 0
v_max_non_paid_invoice 1742 1742 0 0.00 0 175 2 0 0
v_max_non_paid_realizn 3873 3873 0 0.00 0 580 3 0 0
v_min_id_clo_ord 5544 5544 0 0.00 0 1447 4 0 0
v_min_id_clo_res 972 972 0 0.00 0 7759 18 0 0
v_min_id_ord_sup 5577 5577 0 0.00 0 6377 11 0 0
v_min_non_paid_invoice 5959 5959 0 0.00 0 11878 17 0 0
v_min_non_paid_realizn 7349 7349 0 0.00 0 11360 18 0 0
v_our_firm 10830 10830 0 0.00 0 183 1 0 0
v_reserve_write_off 21034 21034 0 0.00 0 2943 4 0 0
x_lock_depdocs_on_canc_invoice 11686 11686 0 0.00 0 16780 108 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 6076 316 0 0
x_make_qstorno_1000_1200 5554 5554 0 0.00 2 44127 1326 0 0
x_make_qstorno_1200_2000 5590 5590 0 0.00 3 140692 3226 0 0
x_make_qstorno_2100_3300 463478 463478 0 0.00 0 57742 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2021 10:38:15 to 24-12-2021 10:38:19.

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
08:07:14 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 14866 8192000000 9711202304 10005684224 792780896 8.823 100 87 174 0 822777840 7971968 22024976
08:09:37 17164 8192000000 9748325472 10054369280 825958592 9.159 100 89 178 1 859203008 11373712 21870704
08:10:56 23060 8192000000 9784968976 10100989952 851020624 9.410 100 82 164 0 894874096 24466608 19386864
08:12:24 12904 8192000000 9850781456 10181632000 904825872 9.946 100 73 146 0 954972480 33921376 16225232
08:13:47 10706 8192000000 9929959456 10271571968 951256496 10.403 100 74 148 0 1029354352 62558176 15539680
08:14:59 4943 8192000000 9956014704 10308526080 987230272 10.755 100 85 170 0 1068996208 64451536 17314400
08:16:06 2672 8192000000 9979370000 10347765760 1022275776 11.094 100 70 140 0 1090082880 53364112 14442992
08:17:11 2131 8192000000 10052862480 10419978240 1045925968 11.322 100 88 175 1 1164452672 100901104 17625600
08:18:16 2342 8192000000 10035058704 10403000320 1061340480 11.469 100 89 178 1 1145444416 66754944 17348992
08:19:19 3404 8192000000 9992749776 10379476992 1069582528 11.548 100 48 96 1 1104201872 24775920 9843424
08:20:25 3754 8192000000 10002897072 10390163456 1084056400 11.686 100 53 103 1 1112331600 17384144 10891056
08:21:30 57911 8192000000 10015486432 10404892672 1086589248 11.710 100 43 86 2 1124654240 28236656 9828336
08:23:28 7885 8192000000 10027003504 10421956608 1092727888 11.769 100 50 100 1 1148660336 44598528 11333920
08:24:37 5836 8192000000 10017399792 10416545792 1097496432 11.814 100 27 55 0 1128911120 25468960 5945728
08:25:47 52334 8192000000 9998547376 10402238464 1097552800 11.814 100 49 98 1 1122466432 13320944 11592688
08:27:40 3892 8192000000 10018449136 10415386624 1098667232 11.825 100 51 102 0 1132569840 22845152 11057456
08:28:49 4846 8192000000 9977581888 10362826752 1045489120 11.317 98 42 85 1 1071061008 15870592 9701296
08:29:57 7199 8192000000 9948310992 10332786688 1020015056 11.072 98 24 49 0 1048284864 22781872 5487936
08:31:07 33516 8192000000 9930328128 10321518592 936847024 10.262 90 33 66 0 963329568 19089808 7392736
08:32:41 7217 8192000000 9964903552 10336215040 1032044432 11.188 100 31 60 1 1069591040 30220848 7325760
08:33:49 6560 8192000000 9970266064 10359685120 1051177392 11.372 100 49 96 0 1085197312 23191616 10828304
08:34:58 44240 8192000000 9979909008 10368331776 1063678048 11.492 100 37 73 1 1083563472 12646832 7238592
08:36:43 8570 8192000000 9971377632 10374418432 1070490400 11.557 100 36 72 2 1096589840 17299856 8799584
08:37:57 19815 8192000000 10058431216 10450067456 1095431344 11.794 100 56 112 0 1162986896 56545296 11010256
08:39:21 19157 8192000000 10065468704 10468380672 1109722944 11.930 100 40 80 1 1165522384 47634928 8164512
08:40:54 56011 8192000000 10103390336 10523701248 1125172000 12.076 100 31 62 1 1199514544 67608288 6734256
08:43:23 7844 8192000000 10147153392 10557755392 1143551648 12.249 100 48 96 2 1258152112 103663520 10936944
08:44:37 5347 8192000000 10191158304 10602995712 1150081232 12.310 100 49 97 1 1306134672 145677920 10375520
08:45:43 10582 8192000000 10202196016 10636537856 1157730672 12.382 100 48 96 1 1318020736 149750912 10539152
08:47:07 4992 8192000000 10224191952 10633367552 1159804560 12.401 100 66 130 0 1331793344 156850352 15138432
08:48:22 3581 8192000000 10266394464 10676199424 1167765760 12.476 100 67 132 1 1366540000 183949616 14824624
08:49:28 1535 8192000000 10273250544 10687512576 1161936784 12.421 99 65 131 0 1384263648 207895776 14431088
08:50:33 1926 8192000000 10283002160 10686513152 1141487872 12.230 98 81 159 4 1368071216 207933952 18649392
08:51:39 2373 8192000000 10279729952 10701774848 1162792096 12.429 99 84 165 2 1384501808 203133056 18576656
08:52:46 9882 8192000000 10270334416 10681585664 1149622496 12.306 100 57 114 2 1367452656 204865072 12965088
08:54:02 3925 8192000000 10324324880 10732314624 1141093008 12.226 99 76 152 0 1424921728 266422768 17405952
08:55:15 4621 8192000000 10349617024 10754945024 1155122832 12.358 100 68 135 1 1454758000 284322528 15312640
08:56:32 2108 8192000000 10331194928 10763096064 1146035440 12.272 100 69 137 0 1427492416 263911856 17545120
08:57:43 15032 8192000000 10374810064 10795048960 1145131024 12.264 100 47 94 0 1469979776 313416864 11431888
08:59:02 5829 8192000000 10380288432 10806263808 1161558672 12.418 100 55 110 0 1486824864 312214816 13051376
09:00:24 18388 8192000000 10408756560 10842619904 1155254160 12.359 100 33 66 2 1494898320 330472016 9172144
09:01:48 18448 8192000000 10362365440 10837336064 1134965664 12.168 99 36 72 1 1476115168 330915984 10233520
09:03:16 26843 8192000000 10279111152 10759643136 1130337872 12.125 99 21 42 2 1384334720 248543744 5453104
09:05:17 42414 8192000000 10059939088 10540371968 1070496080 11.557 96 6 12 0 1167983840 96009840 1477920
09:07:01 42901 8192000000 10070145760 10479906816 1094162192 11.782 97 44 85 4 1129379632 26327168 8890272
09:08:57 62318 8192000000 10083201696 10515230720 1110941376 11.941 98 25 50 0 1161376304 44229152 6205776
09:11:06 89803 8192000000 10046286592 10473619456 1078517120 11.633 95 13 24 1 1107462000 27067776 1877104
09:13:48 128859 8192000000 10097503760 10552295424 1139389568 12.210 100 36 72 0 1205460288 58226192 7844528
09:17:01 15919 8192000000 10098498752 10531205120 1145326992 12.266 100 23 47 3 1184456912 34525712 4604208
09:18:21 42034 8192000000 10123723440 10586529792 1148126080 12.292 100 26 51 0 1229480400 75755520 5598800
09:20:09 55885 8192000000 10191074272 10644041728 1152418976 12.332 99 14 28 0 1258370464 102690624 3260864
09:22:06 55146 8192000000 10192124992 10648125440 1160295664 12.406 100 21 42 0 1286456896 121194512 4966720
09:24:08 50422 8192000000 10114897760 10596569088 1159766768 12.401 100 11 22 0 1245735200 83623936 2344496
09:25:59 75460 8192000000 10120271136 10638327808 1165797456 12.458 100 21 42 0 1254745840 84226880 4721504
09:28:18 62098 8192000000 10101669360 10574405632 1143634752 12.250 99 11 22 0 1191001936 44600272 2766912
09:30:21 55848 8192000000 10085248672 10580242432 1086391552 11.708 94 23 46 0 1149310928 58279392 4639984
09:32:25 68197 8192000000 10136415296 10654453760 1134828976 12.167 98 33 66 0 1255846400 114258832 6758592
09:34:46 40713 8192000000 10094672288 10534096896 1104172960 11.877 96 19 36 3 1124688048 16792912 3722176
09:36:26 48192 8192000000 10092413408 10568937472 1122148880 12.047 98 24 48 0 1193336384 66201504 4986000
09:38:15 120825 8192000000 10057891248 10518581248 1118497632 12.013 98 6 12 0 1176830864 56981232 1352000
09:41:16 27794 8192000000 10063452832 10496995328 1095616944 11.796 97 34 67 3 1127394640 25351696 6426000
09:42:47 42171 8192000000 10032724704 10500030464 1111132336 11.943 99 15 30 0 1149562544 35956368 2473840
09:44:30 59542 8192000000 10027995264 10487701504 1102818784 11.864 99 10 20 0 1135418336 30685344 1914208
09:46:30 40130 8192000000 10060275424 10501468160 1122985280 12.055 100 13 26 0 1144469632 18813200 2671152
09:48:11 18967 8192000000 10070083584 10496225280 1118352848 12.011 99 35 70 0 1142425360 16771296 7301216
09:49:34 36554 8192000000 10098579920 10539327488 1094864512 11.789 96 23 46 0 1149231296 49264080 5102704
09:51:11 81488 8192000000 10092969040 10599088128 1124227424 12.067 99 20 40 0 1231613824 102923120 4463280
09:53:32 86806 8192000000 10122930368 10573082624 1100614048 11.843 97 19 38 1 1180395440 75565712 4215680
09:56:03 61486 8192000000 10073258480 10520907776 1070068480 11.553 96 23 46 1 1131781936 57038432 4675024
09:58:05 68158 8192000000 10059822464 10494812160 1057194704 11.430 96 19 38 1 1083597632 22810208 3592720
10:00:41 65494 8192000000 10031352592 10514149376 1093237264 11.773 99 15 30 0 1167038320 70343024 3458032
10:03:06 74184 8192000000 10105457744 10549370880 1106855056 11.903 99 15 30 0 1183394240 73281840 3257344
10:06:20 66049 8192000000 10067760560 10512588800 1125256192 12.077 100 19 38 1 1180977488 51315856 4405440
10:08:42 15807 8192000000 10064897920 10499227648 1125577648 12.080 99 28 55 2 1152813920 20928720 6307552
10:10:03 48129 8192000000 10143814704 10584875008 1132382032 12.144 99 33 66 0 1225489248 86360688 6746528
10:12:32 65223 8192000000 10085974096 10579755008 1132458320 12.145 99 31 62 1 1206096720 67298032 6340368
10:14:39 42283 8192000000 10112891088 10572529664 1111074864 11.943 97 26 52 0 1158096880 41548384 5473632
10:16:30 100008 8192000000 10063467872 10568220672 1093962288 11.780 96 13 26 2 1143770224 46473824 3334112
10:19:37 58030 8192000000 10081650880 10538577920 1130654272 12.128 99 22 44 0 1171501328 36416000 4431056
10:21:35 64392 8192000000 10090474176 10562580480 1121465120 12.041 98 6 12 1 1179023024 56290704 1267200
10:24:16 50167 8192000000 10111223520 10615914496 1137651552 12.193 99 22 44 0 1220706176 77946528 5108096
10:26:15 67904 8192000000 10097665552 10557136896 1092731664 11.769 96 10 20 0 1138744448 43917472 2095312
10:28:26 44070 8192000000 10087228128 10534199296 1114438288 11.974 98 19 38 0 1165665056 47292432 3934336
10:30:15 34663 8192000000 10123811760 10557505536 1116374288 11.993 99 29 58 1 1207607056 84896512 6336256
10:31:51 86402 8192000000 10103642208 10568744960 1086423904 11.709 96 14 27 0 1181477264 92052544 3000816
10:34:17 66778 8192000000 10036277504 10500558848 1083434176 11.680 97 15 30 0 1151097712 64561488 3102048
10:36:27 19607 8192000000 10100477376 10525708288 1093129344 11.772 98 49 98 0 1173021152 69892192 9999616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2021 10:38:21 to 24-12-2021 10:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2021 10:38:37 to 24-12-2021 10:38:39.

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          253930
MON$OLDEST_ACTIVE               253931
MON$OLDEST_SNAPSHOT             253931
MON$NEXT_TRANSACTION            253931
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                       2062880
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

24.12.21 10:38:40. 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-24 10:38:42 No severe PSQL-related problems occured
24.12.21 10:38:44. End of report.