Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15711860 kB
MemAvailable 30755668 kB
Buffers 0 kB
Cached 15949884 kB
SwapCached 1628 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-09-24 13:37:09
DTS_END                         2021-09-24 13:37:09.8090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43920
TRN_ID                          244716
ATT_ID                          713
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: 9391.32 19978 1126958
customer order: creation 181.99 375 21839
customer order: refuse 15.37 290 1844
order to supplier: creation 48.20 1453 5784
order to supplier: removal 3.41 113155 409
invoice (draft): creation 48.45 3602 5814
invoice (draft): removal 52.00 1048 6240
invoice accept: apply 43.57 18587 5228
invoice accept: cancel 46.65 940 5598
customer reserve: creation 3957.80 186 474936
customer reserve: removal 4683.39 64 562007
realization accept: apply 88.93 187 10672
realization accept: cancel 85.20 116 10224
payment from customer: creation 62.89 119 7547
payment from customer: removal 5.88 93 705
payment to supplier: creation 52.56 140 6307
payment to supplier: removal 3.42 54 410
service: total inventory turnovers 4.16 3618 499
service: total monetary turnovers 6.58 510 790
service: aggregate perf. data 0.84 15756 101
service: refresh index statistics 0.03 239258 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-09-2021 13:37:50 to 24-09-2021 13:37:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8523
TEST_TIME 2 7173
TEST_TIME 3 5491
TEST_TIME 4 5520
TEST_TIME 5 6523
TEST_TIME 6 6451
TEST_TIME 7 6724
TEST_TIME 8 7611
TEST_TIME 9 7471
TEST_TIME 10 7557
TEST_TIME 11 7529
TEST_TIME 12 7666
TEST_TIME 13 7910
TEST_TIME 14 8008
TEST_TIME 15 8077
TEST_TIME 16 7999
TEST_TIME 17 8013
TEST_TIME 18 8080
TEST_TIME 19 8630
TEST_TIME 20 8873
TEST_TIME 21 8962
TEST_TIME 22 8819
TEST_TIME 23 8636
TEST_TIME 24 8913
TEST_TIME 25 8882
TEST_TIME 26 9317
TEST_TIME 27 9547
TEST_TIME 28 9393
TEST_TIME 29 9257
TEST_TIME 30 10218
TEST_TIME 31 11188
TEST_TIME 32 11142
TEST_TIME 33 11024
TEST_TIME 34 11089
TEST_TIME 35 11059
TEST_TIME 36 10885
TEST_TIME 37 10889
TEST_TIME 38 10838
TEST_TIME 39 10894
TEST_TIME 40 10660
TEST_TIME 41 10479
TEST_TIME 42 10452
TEST_TIME 43 10318
TEST_TIME 44 10236
TEST_TIME 45 10126
TEST_TIME 46 10154
TEST_TIME 47 10147
TEST_TIME 48 10161
TEST_TIME 49 10044
TEST_TIME 50 10018
TEST_TIME 51 9910
TEST_TIME 52 9890
TEST_TIME 53 9970
TEST_TIME 54 9926
TEST_TIME 55 9894
TEST_TIME 56 9957
TEST_TIME 57 9948
TEST_TIME 58 9902
TEST_TIME 59 10024
TEST_TIME 60 10064
TEST_TIME 61 10105
TEST_TIME 62 10053
TEST_TIME 63 10079
TEST_TIME 64 10016
TEST_TIME 65 9941
TEST_TIME 66 9878
TEST_TIME 67 9790
TEST_TIME 68 9794
TEST_TIME 69 9702
TEST_TIME 70 9632
TEST_TIME 71 9552
TEST_TIME 72 9556
TEST_TIME 73 9493
TEST_TIME 74 9476
TEST_TIME 75 9475
TEST_TIME 76 9496
TEST_TIME 77 9550
TEST_TIME 78 9519
TEST_TIME 79 9591
TEST_TIME 80 9573
TEST_TIME 81 9592
TEST_TIME 82 9702
TEST_TIME 83 9710
TEST_TIME 84 9651
TEST_TIME 85 9560
TEST_TIME 86 9518
TEST_TIME 87 9505
TEST_TIME 88 9458
TEST_TIME 89 9397
TEST_TIME 90 9370
TEST_TIME 91 9341
TEST_TIME 92 9334
TEST_TIME 93 9450
TEST_TIME 94 9463
TEST_TIME 95 9501
TEST_TIME 96 9470
TEST_TIME 97 9542
TEST_TIME 98 9611
TEST_TIME 99 9590
TEST_TIME 100 9609
TEST_TIME 101 9618
TEST_TIME 102 9603
TEST_TIME 103 9542
TEST_TIME 104 9517
TEST_TIME 105 9486
TEST_TIME 106 9453
TEST_TIME 107 9391
TEST_TIME 108 9342
TEST_TIME 109 9294
TEST_TIME 110 9253
TEST_TIME 111 9291
TEST_TIME 112 9427
TEST_TIME 113 9452
TEST_TIME 114 9415
TEST_TIME 115 9380
TEST_TIME 116 9444
TEST_TIME 117 9502
TEST_TIME 118 9459
TEST_TIME 119 9457
TEST_TIME 120 9391

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-09-2021 13:37:54 to 24-09-2021 13:37:57.

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 1567446 1567446 0 0.00 0 18339 7 0 0
doc_list_biud 1567445 1567445 0 0.00 0 71255 30 0 0
sp_add_invoice_to_stock 5308 5228 80 1.50 26 194816 18587 0 80
sp_cancel_adding_invoice 5598 5598 0 0.00 5 88601 940 0 0
sp_cancel_client_order 1844 1844 0 0.00 4 6657 291 0 0
sp_cancel_customer_reserve 562007 562007 0 0.00 1 27312 65 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 8190 94 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 1071 54 0 0
sp_cancel_supplier_invoice 6246 6240 6 0.09 2 71347 1048 0 6
sp_cancel_supplier_order 409 409 0 0.00 10 955472 113155 0 0
sp_cancel_write_off 10224 10224 0 0.00 1 19940 117 0 0
sp_client_order 21839 21839 0 0.00 4 32829 376 0 0
sp_customer_reserve 474936 474936 0 0.00 0 50576 187 0 0
sp_fill_shopping_cart 33432 33432 0 0.00 1 18409 46 0 0
sp_fill_shopping_cart_clo_res 474936 474936 0 0.00 0 22192 15 0 0
sp_get_clo_for_invoice 5228 5228 0 0.00 0 33239 299 0 0
sp_kill_cost_storno 16937 16937 0 0.00 0 10066 56 0 0
sp_kill_qstorno_ret_qs2qd 1140887 1140887 0 0.00 0 71254 36 0 0
sp_kill_qty_storno 602222 602222 0 0.00 0 71255 77 0 0
sp_lock_selected_doc 21918 21918 0 0.00 0 8178 7 0 0
sp_make_cost_storno 29754 29754 0 0.00 0 15814 51 0 0
sp_multiply_rows_for_pdistr 29754 29754 0 0.00 0 8772 29 0 0
sp_multiply_rows_for_qdistr 21839 21839 0 0.00 0 17905 176 0 0
sp_pay_from_customer 7547 7547 0 0.00 4 27660 120 0 0
sp_pay_to_supplier 6307 6307 0 0.00 4 27074 141 0 0
sp_payment_common 13853 13853 0 0.00 1 6647 27 0 0
sp_qd_handle_on_cancel_clo 1844 1844 0 0.00 0 6652 256 0 0
sp_qd_handle_on_invoice_upd_sts 10826 10826 0 0.00 0 36187 360 0 0
sp_qd_handle_on_reserve_upd_sts 10672 10672 0 0.00 0 15135 59 0 0
sp_reserve_write_off 10672 10672 0 0.00 7 27710 187 0 0
sp_supplier_invoice 5814 5814 0 0.00 7 83059 3603 0 0
sp_supplier_order 5784 5784 0 0.00 6 35742 1454 0 0
srv_aggregate_perf_data 101 101 0 0.00 2980 109377 15757 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2373 124642 40470 0 0
srv_find_qd_qs_mism 1077352 1077352 0 0.00 0 18339 6 0 0
srv_make_invnt_saldo 499 499 0 0.00 55 91102 3619 0 0
srv_make_money_saldo 790 790 0 0.00 1 16124 510 0 0
srv_recalc_idx_stat 4 4 0 0.00 103359 350058 239258 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5228 5228 0 0.00 0 10694 176 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 20754 167 0 0
t$perf-norm:sp_cancel_client_order 1844 1844 0 0.00 0 4960 13 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 2002 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 671 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 200 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 3814 23 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 17907 1500 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 5272 16 0 0
t$perf-norm:sp_client_order 21839 21839 0 0.00 0 5975 17 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 3492 10 0 0
t$perf-norm:sp_pay_from_customer 7547 7547 0 0.00 0 7906 11 0 0
t$perf-norm:sp_pay_to_supplier 6307 6307 0 0.00 0 5980 7 0 0
t$perf-norm:sp_reserve_write_off 10672 10672 0 0.00 0 6808 9 0 0
t$perf-norm:sp_supplier_invoice 5814 5814 0 0.00 0 4976 17 0 0
t$perf-norm:sp_supplier_order 5784 5784 0 0.00 0 15263 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 455 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 1879 12 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 459 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 43 12 0 0
v_add_invoice_to_stock 10614 10454 160 1.50 0 27630 34 0 160
v_all_customers 39624 39624 0 0.00 0 237 1 0 0
v_all_suppliers 32144 32144 0 0.00 0 94 1 0 0
v_all_wares 43678 43678 0 0.00 0 241 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 5531 14 0 0
v_cancel_client_order 3688 3688 0 0.00 0 1011 3 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 565 4 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 17555 17 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 173 2 0 0
v_cancel_supplier_invoice 814 802 12 1.47 0 2081 15 0 12
v_cancel_supplier_order 818 818 0 0.00 0 1580 7 0 0
v_cancel_write_off 2120 2120 0 0.00 0 7592 20 0 0
v_max_id_clo_ord 5784 5784 0 0.00 0 540 1 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 7658 19 0 0
v_max_id_ord_sup 5809 5809 0 0.00 0 5001 6 0 0
v_max_non_paid_invoice 1827 1827 0 0.00 0 510 2 0 0
v_max_non_paid_realizn 4036 4036 0 0.00 0 3014 4 0 0
v_min_id_clo_ord 5784 5784 0 0.00 0 5100 10 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 9619 15 0 0
v_min_id_ord_sup 5809 5809 0 0.00 0 7084 12 0 0
v_min_non_paid_invoice 6306 6306 0 0.00 0 6226 21 0 0
v_min_non_paid_realizn 7547 7547 0 0.00 0 6201 17 0 0
v_our_firm 11076 11076 0 0.00 0 94 1 0 0
v_reserve_write_off 21344 21344 0 0.00 0 27603 9 0 0
x_lock_depdocs_on_canc_invoice 11437 11437 0 0.00 0 16414 109 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 10549 338 0 0
x_make_qstorno_1000_1200 5784 5784 0 0.00 2 35732 1366 0 0
x_make_qstorno_1200_2000 5814 5814 0 0.00 3 70816 3427 0 0
x_make_qstorno_2100_3300 474936 474936 0 0.00 0 37418 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2021 13:37:58 to 24-09-2021 13:38:02.

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 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 28164 8192000000 9722194480 10017955840 791492016 8.810 100 88 177 0 821036640 7954000 21590624
11:09:52 23396 8192000000 9765313152 10072891392 839518480 9.295 100 84 168 0 870296000 10549216 20228304
11:11:31 22397 8192000000 9822159712 10146725888 884715408 9.747 100 77 154 0 925998224 23210688 18072128
11:12:59 8505 8192000000 9884934640 10222964736 934311952 10.237 100 77 152 2 996505824 45632544 16561328
11:14:13 8361 8192000000 9935457600 10287333376 979788464 10.682 100 79 159 0 1051157776 55048400 16320912
11:15:26 6269 8192000000 9971551264 10337390592 1010594480 10.981 100 74 148 0 1093631856 68108688 14928688
11:16:37 1781 8192000000 9998975280 10366074880 1038745264 11.253 100 80 161 0 1110190240 55546608 15898368
11:17:40 1366 8192000000 10011396656 10379190272 1054157312 11.401 100 82 163 1 1115736384 45594528 15984544
11:18:42 3134 8192000000 9992720752 10378067968 1061850016 11.474 100 45 90 0 1104943632 33163952 9929664
11:19:46 9792 8192000000 9998372464 10378522624 1070099936 11.553 100 68 135 1 1101544784 16953328 14491520
11:21:01 7948 8192000000 9997825792 10392305664 1076115168 11.610 100 28 56 1 1119679408 38184048 5380192
11:22:10 9829 8192000000 9984954368 10384302080 1080368576 11.651 100 24 45 2 1099889936 14821408 4699952
11:23:22 54897 8192000000 10010291936 10398285824 1087810976 11.722 100 43 86 2 1110356448 13297088 9248384
11:25:18 8960 8192000000 10013704688 10416287744 1092414944 11.766 100 32 61 0 1116911968 17816416 6680608
11:26:27 9423 8192000000 10002971312 10413551616 1095270848 11.793 100 31 61 1 1121328704 19185264 6872592
11:27:37 5943 8192000000 10009733136 10410065920 1092340096 11.765 100 43 84 3 1120273856 18540272 9393488
11:28:46 59345 8192000000 9937102896 10332663808 985932864 10.742 94 36 72 0 1018857616 25660320 7264432
11:30:52 11492 8192000000 9918390432 10304270336 981231712 10.696 96 26 51 3 1011522176 24783936 5506528
11:32:03 5596 8192000000 9921308288 10298990592 1004163072 10.919 99 44 85 1 1029452208 15597136 9692000
11:33:10 10614 8192000000 9967869120 10366251008 1040177504 11.266 100 30 59 1 1075616224 28899920 6538800
11:34:23 40651 8192000000 9956915584 10361974784 1050434864 11.365 100 29 56 1 1085741440 29006848 6299728
11:36:10 7007 8192000000 9980548848 10383331328 1061333088 11.469 100 34 66 3 1085794000 16462864 7998048
11:37:21 31211 8192000000 10012999824 10406846464 1089928624 11.742 100 54 108 1 1115429616 13810192 11690800
11:38:58 80451 8192000000 10053916304 10470735872 1112901808 11.960 100 46 90 1 1180728240 58394080 9432352
11:41:32 29692 8192000000 10088301312 10496708608 1124674080 12.071 100 38 76 3 1202778576 69445632 8658864
11:43:05 9795 8192000000 10103539344 10510626816 1134804560 12.167 100 48 94 2 1204250560 59657360 9788640
11:44:20 3412 8192000000 10144130624 10547204096 1141770560 12.232 100 73 146 0 1252639632 94845408 16023664
11:45:31 8579 8192000000 10182645120 10599071744 1147704752 12.288 100 62 121 3 1282901936 122296832 12900352
11:46:46 8238 8192000000 10154160176 10570412032 1162116752 12.423 100 62 122 1 1276053152 101237296 12699104
11:47:56 8635 8192000000 10154494336 10568101888 1155473568 12.361 99 53 104 1 1265861824 98754672 11633584
11:49:08 3913 8192000000 10188421088 10595590144 1160500528 12.408 100 61 120 0 1283570288 110610752 12459008
11:50:15 3922 8192000000 10238261040 10637811712 1149791472 12.308 100 81 159 0 1341344992 174518192 17035328
11:51:26 5733 8192000000 10252993200 10655232000 1151587856 12.324 100 78 154 0 1349803984 181308448 16907680
11:52:38 6639 8192000000 10246897120 10654531584 1139977104 12.215 100 64 128 0 1349251616 195040592 14233920
11:53:50 3363 8192000000 10290775984 10694959104 1139039488 12.206 100 74 145 0 1390198224 234730800 16427936
11:54:58 9395 8192000000 10299300976 10733469696 1142506816 12.239 100 48 95 1 1410142096 255731408 11903872
11:56:15 10849 8192000000 10297251568 10725470208 1112460944 11.956 99 53 106 0 1403476176 278640080 12375152
11:57:33 7634 8192000000 10263912688 10676957184 1133718448 12.156 100 37 74 2 1366565328 224151552 8695328
11:58:49 18891 8192000000 10323610496 10741395456 1143075456 12.244 100 36 72 0 1443798288 291379104 9343728
12:00:11 11515 8192000000 10404882720 10825744384 1155187280 12.358 100 37 74 0 1494700304 330161104 9351920
12:01:30 12546 8192000000 10410856000 10846703616 1134146672 12.160 99 38 76 1 1506738784 362398240 10193872
12:02:50 2372 8192000000 10440672496 10848731136 1148444048 12.295 100 82 161 1 1532799616 364241248 20114320
12:03:55 13429 8192000000 10395223040 10846015488 1141276336 12.228 99 32 64 1 1503436016 353668752 8490928
12:05:35 39784 8192000000 10432525776 10861805568 1127749072 12.100 99 41 81 0 1522919728 385088048 10082608
12:07:47 46308 8192000000 10112483680 10712461312 1111302960 11.945 99 9 18 0 1348945104 235246720 2395424
12:09:39 49523 8192000000 10113259808 10530820096 1142369168 12.238 99 26 51 1 1186263600 38307536 5586896
12:11:28 29875 8192000000 10067407568 10503626752 1080141360 11.649 94 18 36 0 1103768992 20034944 3592688
12:13:06 14571 8192000000 10084175856 10505904128 1133755408 12.157 99 50 99 0 1174359856 30052992 10551456
12:14:25 15420 8192000000 10119753600 10589556736 1147740656 12.288 100 43 86 0 1216572976 59816368 9015952
12:15:45 124641 8192000000 10089251824 10555793408 1104373280 11.879 96 16 33 0 1175138336 67581808 3183248
12:19:56 53529 8192000000 10105601536 10567614464 1156918064 12.374 100 37 74 0 1208045904 43681968 7445872
12:21:55 44571 8192000000 10147001328 10587295744 1162048528 12.422 100 36 72 1 1237434464 66720752 8665184
12:23:48 50528 8192000000 10124625728 10613956608 1159318592 12.397 100 23 47 0 1257843360 93349056 5175712
12:25:52 31592 8192000000 10093194368 10550325248 1148899312 12.299 99 5 10 0 1185638272 35725184 1013776
12:27:54 38803 8192000000 10100561472 10569039872 1159366288 12.397 100 22 42 1 1209937488 46207456 4363744
12:29:42 52336 8192000000 10079802640 10571702272 1134765712 12.166 99 17 30 3 1213960560 75183760 4011088
12:31:40 38804 8192000000 10098864320 10548256768 1121576816 12.042 97 38 76 0 1181576080 51498944 8500320
12:33:38 48750 8192000000 10083321728 10546139136 1084490080 11.690 94 18 35 3 1130976240 42321520 4164640
12:35:30 31741 8192000000 10153486192 10614554624 1116183616 11.991 98 22 44 1 1209264048 88389856 4690576
12:37:02 74975 8192000000 10086902096 10516553728 1080073584 11.648 95 18 36 0 1148934064 65071872 3788608
12:39:18 36510 8192000000 10054753120 10490064896 1066564464 11.519 95 23 46 1 1109676848 38469920 4642464
12:41:26 87761 8192000000 10056829504 10511306752 1121338080 12.040 100 42 84 2 1174334160 44284864 8711216
12:43:56 27896 8192000000 10063887952 10517585920 1112692656 11.958 99 25 50 0 1153339968 35588048 5059264
12:45:24 49865 8192000000 10029160528 10491506688 1099804992 11.836 98 10 20 1 1133326928 31349840 2172096
12:47:14 71169 8192000000 10064514720 10520125440 1073641680 11.587 95 12 24 1 1119437888 42910304 2885904
12:49:27 80361 8192000000 10047194336 10511425536 1098880864 11.827 98 14 28 0 1148339728 46072688 3386176
12:51:50 27524 8192000000 10065147120 10513887232 1088527056 11.729 97 23 46 0 1131321568 38065024 4729488
12:53:21 50850 8192000000 10099663760 10571378688 1079582608 11.643 96 26 52 0 1172143808 86997504 5563696
12:55:12 55019 8192000000 10081096624 10550956032 1102612592 11.862 99 22 45 0 1182019712 74213504 5193616
12:57:07 25908 8192000000 10080595696 10507665408 1098099024 11.820 98 31 61 0 1150884368 45987664 6797680
12:58:34 49297 8192000000 10126673808 10620719104 1128073968 12.103 100 25 51 0 1232470624 99145920 5250736
13:00:26 51547 8192000000 10042391328 10490232832 1107551504 11.909 99 14 28 1 1143752144 33346352 2854288
13:02:23 89688 8192000000 10082304384 10544656384 1126683760 12.090 100 40 80 2 1201460784 66251488 8525536
13:05:13 47491 8192000000 10073555376 10540593152 1134102752 12.160 100 5 10 0 1172274816 37313360 858704
13:07:14 51902 8192000000 10140498640 10608996352 1132614208 12.146 99 13 26 0 1215445776 80055104 2776464
13:09:06 52983 8192000000 10190712336 10663120896 1106856368 11.903 96 20 40 1 1252658752 141359888 4442496
13:11:30 63458 8192000000 10065638816 10515329024 1113052736 11.961 98 14 28 0 1190267968 74326320 2888912
13:13:39 67178 8192000000 10134320064 10621853696 1118769776 12.015 98 12 24 0 1243352800 121231840 3351184
13:16:10 35269 8192000000 10087528336 10528923648 1126711408 12.090 99 29 57 0 1165883824 33205536 5966880
13:17:53 72360 8192000000 10090010848 10570608640 1101473472 11.852 97 31 62 0 1180730064 73326608 5929984
13:20:08 121899 8192000000 10069066304 10541674496 1107470560 11.908 98 21 42 0 1147462784 35368400 4623824
13:23:14 67857 8192000000 10052499152 10515509248 1123304304 12.058 99 5 10 0 1165590944 41188672 1097968
13:25:51 50581 8192000000 10186826624 10634412032 1133913344 12.158 99 22 44 2 1243660896 104554896 5192656
13:28:02 62919 8192000000 10146729792 10725146624 1128558080 12.108 100 10 20 1 1322705520 191401440 2746000
13:30:14 73574 8192000000 10041840624 10484957184 1059876432 11.455 93 19 38 0 1081627024 18016896 3733696
13:32:35 64650 8192000000 10167507168 10664177664 1134880864 12.167 100 24 48 1 1254055376 113497136 5677376
13:35:14 23611 8192000000 10058600784 10488954880 1107006176 11.904 99 36 70 0 1138289584 24466720 6816688
13:36:38 35861 8192000000 9397006336 9722286080 516712176 5.933 46 22 42 1 540032208 19315072 4004960

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-09-2021 13:38:04 to 24-09-2021 13:38:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-09-2021 13:38:20 to 24-09-2021 13:38:22.

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          245207
MON$OLDEST_ACTIVE               245208
MON$OLDEST_SNAPSHOT             245208
MON$NEXT_TRANSACTION            245208
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                       2070880
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             817
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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.09.21 13:38:23. 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-09-24 13:38:26 No severe PSQL-related problems occured
24.09.21 13:38:27. End of report.