Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.08.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 16123640 kB
MemAvailable 31025876 kB
Buffers 0 kB
Cached 15521652 kB
SwapCached 2580 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-08-05 13:37:03
DTS_END                         2021-08-05 13:37:03.7020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46528
TRN_ID                          238444
ATT_ID                          692
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: 9204.66 20815 1104559
customer order: creation 170.74 381 20489
customer order: refuse 15.45 350 1854
order to supplier: creation 45.94 1393 5513
order to supplier: removal 3.65 96601 438
invoice (draft): creation 45.14 3768 5417
invoice (draft): removal 53.11 859 6373
invoice accept: apply 41.60 18012 4992
invoice accept: cancel 47.17 753 5660
customer reserve: creation 3840.66 178 460879
customer reserve: removal 4645.38 59 557445
realization accept: apply 83.98 181 10077
realization accept: cancel 82.95 122 9954
payment from customer: creation 59.85 120 7182
payment from customer: removal 5.53 85 663
payment to supplier: creation 48.80 144 5856
payment to supplier: removal 3.73 80 447
service: total inventory turnovers 3.93 3348 472
service: total monetary turnovers 6.10 491 732
service: aggregate perf. data 0.93 12542 112
service: refresh index statistics 0.03 276840 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-08-2021 13:37:53 to 05-08-2021 13:37:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8340
TEST_TIME 2 5087
TEST_TIME 3 4925
TEST_TIME 4 4268
TEST_TIME 5 4526
TEST_TIME 6 4646
TEST_TIME 7 4732
TEST_TIME 8 5393
TEST_TIME 9 5422
TEST_TIME 10 6177
TEST_TIME 11 6516
TEST_TIME 12 6475
TEST_TIME 13 6806
TEST_TIME 14 6852
TEST_TIME 15 7521
TEST_TIME 16 7736
TEST_TIME 17 7982
TEST_TIME 18 8610
TEST_TIME 19 8769
TEST_TIME 20 9161
TEST_TIME 21 9264
TEST_TIME 22 9493
TEST_TIME 23 9554
TEST_TIME 24 9837
TEST_TIME 25 10899
TEST_TIME 26 10935
TEST_TIME 27 10756
TEST_TIME 28 10787
TEST_TIME 29 10587
TEST_TIME 30 10705
TEST_TIME 31 10779
TEST_TIME 32 10646
TEST_TIME 33 10425
TEST_TIME 34 10233
TEST_TIME 35 10235
TEST_TIME 36 10127
TEST_TIME 37 10009
TEST_TIME 38 10060
TEST_TIME 39 9979
TEST_TIME 40 9900
TEST_TIME 41 9982
TEST_TIME 42 9919
TEST_TIME 43 9913
TEST_TIME 44 9825
TEST_TIME 45 9738
TEST_TIME 46 9803
TEST_TIME 47 9680
TEST_TIME 48 9663
TEST_TIME 49 9725
TEST_TIME 50 9728
TEST_TIME 51 9689
TEST_TIME 52 9692
TEST_TIME 53 9650
TEST_TIME 54 9766
TEST_TIME 55 9804
TEST_TIME 56 9829
TEST_TIME 57 9802
TEST_TIME 58 9879
TEST_TIME 59 9941
TEST_TIME 60 9885
TEST_TIME 61 9740
TEST_TIME 62 9670
TEST_TIME 63 9638
TEST_TIME 64 9652
TEST_TIME 65 9579
TEST_TIME 66 9504
TEST_TIME 67 9483
TEST_TIME 68 9482
TEST_TIME 69 9523
TEST_TIME 70 9548
TEST_TIME 71 9493
TEST_TIME 72 9564
TEST_TIME 73 9568
TEST_TIME 74 9543
TEST_TIME 75 9539
TEST_TIME 76 9551
TEST_TIME 77 9620
TEST_TIME 78 9546
TEST_TIME 79 9444
TEST_TIME 80 9426
TEST_TIME 81 9429
TEST_TIME 82 9372
TEST_TIME 83 9317
TEST_TIME 84 9263
TEST_TIME 85 9232
TEST_TIME 86 9197
TEST_TIME 87 9322
TEST_TIME 88 9287
TEST_TIME 89 9362
TEST_TIME 90 9332
TEST_TIME 91 9334
TEST_TIME 92 9371
TEST_TIME 93 9329
TEST_TIME 94 9387
TEST_TIME 95 9415
TEST_TIME 96 9484
TEST_TIME 97 9475
TEST_TIME 98 9520
TEST_TIME 99 9444
TEST_TIME 100 9370
TEST_TIME 101 9363
TEST_TIME 102 9293
TEST_TIME 103 9230
TEST_TIME 104 9290
TEST_TIME 105 9272
TEST_TIME 106 9246
TEST_TIME 107 9210
TEST_TIME 108 9182
TEST_TIME 109 9144
TEST_TIME 110 9159
TEST_TIME 111 9134
TEST_TIME 112 9113
TEST_TIME 113 9123
TEST_TIME 114 9228
TEST_TIME 115 9281
TEST_TIME 116 9263
TEST_TIME 117 9238
TEST_TIME 118 9241
TEST_TIME 119 9280
TEST_TIME 120 9205

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-08-2021 13:37:57 to 05-08-2021 13:38:00.

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 1531756 1531756 0 0.00 0 17472 7 0 0
doc_list_biud 1531755 1531755 0 0.00 0 94395 28 0 0
sp_add_invoice_to_stock 5093 4992 101 1.98 30 197847 18013 0 101
sp_cancel_adding_invoice 5660 5660 0 0.00 6 67022 754 0 0
sp_cancel_client_order 1854 1854 0 0.00 5 37072 351 0 0
sp_cancel_customer_reserve 557445 557445 0 0.00 1 33451 60 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 8652 85 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 2669 81 0 0
sp_cancel_supplier_invoice 6380 6373 7 0.10 1 94501 860 0 7
sp_cancel_supplier_order 438 438 0 0.00 8 922051 96601 0 0
sp_cancel_write_off 9954 9954 0 0.00 1 11684 123 0 0
sp_client_order 20489 20489 0 0.00 4 27734 381 0 0
sp_customer_reserve 460879 460879 0 0.00 0 120477 179 0 0
sp_fill_shopping_cart 31397 31397 0 0.00 1 13473 41 0 0
sp_fill_shopping_cart_clo_res 460876 460876 0 0.00 0 26615 15 0 0
sp_get_clo_for_invoice 4989 4989 0 0.00 0 24499 325 0 0
sp_kill_cost_storno 16724 16724 0 0.00 0 11627 55 0 0
sp_kill_qstorno_ret_qs2qd 1131655 1131655 0 0.00 0 94392 33 0 0
sp_kill_qty_storno 596788 596788 0 0.00 0 94394 70 0 0
sp_lock_selected_doc 20937 20937 0 0.00 0 10092 5 0 0
sp_make_cost_storno 28103 28103 0 0.00 0 15700 56 0 0
sp_multiply_rows_for_pdistr 28102 28102 0 0.00 0 8277 34 0 0
sp_multiply_rows_for_qdistr 20489 20489 0 0.00 0 21716 182 0 0
sp_pay_from_customer 7182 7182 0 0.00 4 10495 121 0 0
sp_pay_to_supplier 5856 5856 0 0.00 5 15707 144 0 0
sp_payment_common 13038 13038 0 0.00 1 10388 21 0 0
sp_qd_handle_on_cancel_clo 1854 1854 0 0.00 0 37065 305 0 0
sp_qd_handle_on_invoice_upd_sts 10648 10648 0 0.00 0 30277 322 0 0
sp_qd_handle_on_reserve_upd_sts 10076 10076 0 0.00 0 21143 47 0 0
sp_reserve_write_off 10077 10077 0 0.00 7 24322 182 0 0
sp_supplier_invoice 5417 5417 0 0.00 7 103416 3769 0 0
sp_supplier_order 5513 5513 0 0.00 6 28909 1394 0 0
srv_aggregate_perf_data 112 112 0 0.00 3184 104993 12542 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2325 184902 47685 0 0
srv_find_qd_qs_mism 1057207 1057207 0 0.00 0 10325 6 0 0
srv_make_invnt_saldo 472 472 0 0.00 65 95841 3349 0 0
srv_make_money_saldo 732 732 0 0.00 1 11439 491 0 0
srv_recalc_idx_stat 4 4 0 0.00 144367 427212 276841 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4992 4992 0 0.00 0 13587 147 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 15991 122 0 0
t$perf-norm:sp_cancel_client_order 1854 1854 0 0.00 0 4435 12 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 2311 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 4567 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 2016 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 6589 28 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 16605 1281 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 8137 13 0 0
t$perf-norm:sp_client_order 20489 20489 0 0.00 0 11451 19 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 655 3 0 0
t$perf-norm:sp_pay_from_customer 7182 7182 0 0.00 0 12981 10 0 0
t$perf-norm:sp_pay_to_supplier 5856 5856 0 0.00 0 5322 8 0 0
t$perf-norm:sp_reserve_write_off 10077 10077 0 0.00 0 8940 12 0 0
t$perf-norm:sp_supplier_invoice 5417 5417 0 0.00 0 7693 16 0 0
t$perf-norm:sp_supplier_order 5513 5513 0 0.00 0 5450 12 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 1014 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 18 1 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 645 6 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 568 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10178 9976 202 1.98 0 7633 22 0 202
v_all_customers 37554 37554 0 0.00 0 21 1 0 0
v_all_suppliers 30390 30390 0 0.00 0 14 1 0 0
v_all_wares 40978 40978 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 3804 11 0 0
v_cancel_client_order 3708 3708 0 0.00 0 6338 11 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 3045 9 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 6930 15 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 2604 11 0 0
v_cancel_supplier_invoice 858 844 14 1.63 0 5257 23 0 14
v_cancel_supplier_order 876 876 0 0.00 0 6339 13 0 0
v_cancel_write_off 1974 1974 0 0.00 0 3610 18 0 0
v_max_id_clo_ord 5507 5507 0 0.00 0 1258 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 3843 20 0 0
v_max_id_ord_sup 5400 5400 0 0.00 0 4923 6 0 0
v_max_non_paid_invoice 1568 1568 0 0.00 0 638 2 0 0
v_max_non_paid_realizn 3752 3752 0 0.00 0 2183 3 0 0
v_min_id_clo_ord 5507 5507 0 0.00 0 4524 5 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 3674 11 0 0
v_min_id_ord_sup 5400 5400 0 0.00 0 3809 6 0 0
v_min_non_paid_invoice 5856 5856 0 0.00 0 5349 14 0 0
v_min_non_paid_realizn 7182 7182 0 0.00 0 4679 15 0 0
v_our_firm 10284 10284 0 0.00 0 15 1 0 0
v_reserve_write_off 20152 20152 0 0.00 0 6417 9 0 0
x_lock_depdocs_on_canc_invoice 11611 11611 0 0.00 0 25943 107 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 5052 265 0 0
x_make_qstorno_1000_1200 5513 5513 0 0.00 2 28899 1322 0 0
x_make_qstorno_1200_2000 5417 5417 0 0.00 2 103355 3611 0 0
x_make_qstorno_2100_3300 460879 460879 0 0.00 0 120472 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-08-2021 13:38:01 to 05-08-2021 13:38:05.

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:07 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:08 28959 8192000000 9726330032 10032455680 812324576 9.021 100 81 163 0 839332384 7810784 19197024
11:09:42 23555 8192000000 9772519712 10084139008 847079856 9.371 100 84 168 0 883644336 16703808 19860672
11:11:12 26560 8192000000 9817789344 10138853376 881639600 9.716 100 89 178 0 927238672 24873344 20725728
11:12:46 11300 8192000000 9885955584 10225147904 930694384 10.201 100 71 142 0 996361360 50612480 15054496
11:14:00 6188 8192000000 9947649648 10291396608 979326288 10.678 100 89 178 1 1058435648 60991200 18118160
11:15:09 4706 8192000000 9980870080 10333818880 1005184672 10.929 100 84 166 0 1094712944 72432016 17096256
11:16:16 5389 8192000000 10024635152 10391773184 1034123664 11.208 100 82 161 1 1128534336 78537984 15872688
11:17:23 1723 8192000000 10028817760 10398265344 1057733040 11.435 100 93 185 0 1145838736 68898496 19207200
11:18:25 18160 8192000000 9990831824 10370805760 1065668560 11.511 100 68 135 2 1109032960 29359744 14004656
11:19:44 4107 8192000000 9995498880 10382946304 1074553920 11.596 100 42 83 1 1105931344 22024880 9352544
11:20:48 5668 8192000000 9995274256 10402865152 1080473360 11.652 100 35 70 0 1121046384 33136304 7436720
11:21:55 8179 8192000000 10001370160 10400014336 1087712688 11.721 100 36 71 1 1124121328 28776224 7632416
11:23:08 34595 8192000000 9996128304 10403442688 1091236944 11.754 100 30 60 0 1109328608 11523008 6568656
11:25:14 4293 8192000000 10034877264 10442436608 1097132480 11.810 100 51 102 3 1159548976 52014624 10401872
11:26:20 5319 8192000000 10003460288 10396430336 1090904592 11.751 100 56 113 2 1114303344 11280752 12118000
11:27:29 7327 8192000000 9960325792 10359267328 1045226944 11.315 99 37 71 1 1067560112 14347360 7985808
11:28:36 52643 8192000000 9946416608 10335653888 982870112 10.712 93 31 61 2 1014148336 24994128 6284096
11:30:29 6471 8192000000 9958391040 10349117440 1019667216 11.069 98 24 47 2 1051810080 27226400 4916464
11:31:38 10370 8192000000 9953226656 10338775040 1014815120 11.022 99 54 108 3 1038644928 10850992 12978816
11:32:52 9713 8192000000 9953580192 10354454528 1047134512 11.333 100 30 59 1 1070928192 17012448 6781232
11:34:02 39730 8192000000 9977246896 10368942080 1056301248 11.421 100 37 74 0 1085675920 21494096 7880576
11:35:44 7920 8192000000 9982333632 10376196096 1064772016 11.502 100 38 76 0 1085029040 11236320 9020704
11:36:52 14199 8192000000 9992288304 10389102592 1080982496 11.657 100 42 79 2 1110528192 20788784 8756912
11:38:16 22894 8192000000 10046789952 10443567104 1110896704 11.941 100 49 95 1 1157479552 36710496 9872352
11:39:41 94463 8192000000 10085808016 10501783552 1133591904 12.155 100 39 76 1 1190539456 48608512 8339040
11:42:25 14024 8192000000 10149343792 10565001216 1145117376 12.264 100 43 86 0 1264683920 110209760 9356784
11:43:42 5911 8192000000 10188711568 10598617088 1156324608 12.369 100 52 103 2 1292845792 125497488 11023696
11:44:51 24206 8192000000 10225195056 10654756864 1162300624 12.425 100 52 104 1 1339688464 165241904 12145936
11:46:19 4171 8192000000 10231797408 10644996096 1155349392 12.360 99 51 102 0 1323817664 157254576 11213696
11:47:26 4276 8192000000 10266987536 10696740864 1161701328 12.419 99 64 126 1 1373991760 198354864 13935568
11:48:37 4689 8192000000 10254668160 10669207552 1180563760 12.595 100 64 128 2 1353396352 158214384 14618208
11:49:52 5858 8192000000 10309421232 10730557440 1183391280 12.622 100 66 133 0 1413263344 214444128 15427936
11:51:06 2323 8192000000 10345129856 10752122880 1179131232 12.582 100 79 158 1 1441476160 245147024 17197904
11:52:14 3767 8192000000 10359587856 10785665024 1159031136 12.394 98 72 144 0 1448552608 272756528 16764944
11:53:21 4186 8192000000 10357676896 10771623936 1161059376 12.413 100 69 138 0 1467000208 290028800 15912032
11:54:29 4584 8192000000 10385330400 10801156096 1165135472 12.451 100 63 126 3 1475538656 295031952 15371232
11:55:39 18442 8192000000 10341247600 10787672064 1156234528 12.368 99 36 72 1 1440697760 275080656 9382576
11:57:05 15680 8192000000 10357463008 10811838464 1158824816 12.392 100 39 78 0 1470747072 302056784 9865472
11:58:23 11717 8192000000 10341532672 10788765696 1156960816 12.375 100 35 70 1 1453419856 287800352 8658688
11:59:39 5813 8192000000 10301662720 10759385088 1132715616 12.147 99 32 64 1 1388966336 248762768 7487952
12:00:49 19559 8192000000 10254318592 10706264064 1077104272 11.620 97 24 48 1 1351672064 268153808 6413984
12:02:11 34911 8192000000 10087592464 10584928256 1080851184 11.656 97 18 36 0 1254921664 169966432 4104048
12:03:47 39110 8192000000 10046807072 10467995648 1095167056 11.792 98 26 52 0 1127711856 27121824 5422976
12:05:28 53796 8192000000 10055564176 10474311680 1099045648 11.829 99 34 65 2 1144899568 38881856 6972064
12:07:26 95313 8192000000 10053222400 10492932096 1075152000 11.601 95 14 28 1 1147484960 69738848 2594112
12:10:02 98247 8192000000 10101897488 10526924800 1116253232 11.992 99 43 83 1 1174712640 48738352 9721056
12:12:47 104348 8192000000 10099939808 10543861760 1124380368 12.068 99 29 58 0 1175315936 44471456 6464112
12:15:38 22956 8192000000 10107329888 10542833664 1129201312 12.114 99 29 57 3 1197715424 62168272 6345840
12:17:01 118759 8192000000 10111270096 10550681600 1140201152 12.217 99 22 44 1 1258657280 112872032 5584096
12:20:18 42375 8192000000 10085175648 10531475456 1137124848 12.188 99 14 28 0 1198366224 58016400 3224976
12:23:53 48512 8192000000 10100784160 10563432448 1149906576 12.309 100 21 42 3 1194727360 40502400 4318384
12:25:42 42319 8192000000 10094753152 10567835648 1139976096 12.215 99 30 61 1 1190519264 43915920 6627248
12:27:36 64866 8192000000 10086101808 10523586560 1103574544 11.872 96 13 26 0 1156114672 50097392 2442736
12:29:44 65114 8192000000 10151527312 10601865216 1144481312 12.258 100 27 54 0 1212370128 62382528 5506288
12:31:49 55990 8192000000 10100680336 10547806208 1088109040 11.725 95 15 30 0 1173390704 81980512 3301152
12:33:49 26914 8192000000 10111498848 10541817856 1129411408 12.116 100 52 101 1 1182759376 43472096 9875872
12:35:22 64838 8192000000 10053354784 10538897408 1071144768 11.563 94 23 46 1 1124302064 48623840 4533456
12:37:28 113808 8192000000 10065783856 10520141824 1108140352 11.915 98 30 59 0 1146623632 32333648 6149632
12:40:40 36685 8192000000 10064288224 10530832384 1103245808 11.868 98 33 65 1 1157160464 46951152 6963504
12:43:34 48402 8192000000 10081776544 10545709056 1105195056 11.887 98 14 28 0 1148308800 40310320 2803424
12:45:32 56724 8192000000 10090481136 10538270720 1051139248 11.372 94 22 44 0 1156178384 99827328 5211808
12:47:29 41088 8192000000 10074927184 10500177920 1094507808 11.785 98 27 54 0 1135408704 35445072 5455824
12:49:16 61448 8192000000 10060112336 10502975488 1086187792 11.706 97 17 33 1 1138766128 49249872 3328464
12:51:22 55254 8192000000 10041927408 10478723072 1053546608 11.395 94 28 56 4 1119039952 59646992 5846352
12:53:21 55832 8192000000 10074842624 10574565376 1105359760 11.888 99 28 56 1 1158968448 48196848 5411840
12:55:24 125101 8192000000 10101783072 10537713664 1113411216 11.965 99 37 74 0 1185522704 64733408 7378080
12:58:29 41492 8192000000 10044037504 10537308160 1115651904 11.986 100 7 14 1 1155070944 38305008 1114032
13:01:59 49217 8192000000 10135699024 10583990272 1145820528 12.270 100 13 26 0 1208863184 60267968 2774688
13:03:50 81521 8192000000 10104746032 10622783488 1153196864 12.339 100 15 30 1 1269550176 113193984 3159328
13:06:16 81441 8192000000 10077479536 10542669824 1099395424 11.832 96 12 24 0 1151469888 49667136 2407328
13:08:39 31797 8192000000 10125485024 10595192832 1136941648 12.187 98 38 76 1 1209116464 64112768 8062048
13:10:11 22497 8192000000 10111106176 10538119168 1147372336 12.285 99 43 82 0 1186395312 30596544 8426432
13:11:37 52731 8192000000 10115516480 10598236160 1144455808 12.257 99 12 24 1 1217028016 70295216 2276992
13:13:31 184902 8192000000 10083820736 10611695616 1104083744 11.876 95 6 12 0 1140504128 35206432 1213952
13:18:01 60296 8192000000 10109319632 10595586048 1134054240 12.160 98 22 43 0 1208432048 69483296 4894512
13:20:12 68437 8192000000 10138616288 10633457664 1144386656 12.257 99 17 34 0 1231268544 82884432 3997456
13:22:41 61170 8192000000 10059425200 10531868672 1121833312 12.044 97 12 23 1 1165409056 41049952 2525792
13:24:45 82170 8192000000 10100193120 10589884416 1114026960 11.971 98 15 31 0 1145552592 28544048 2981584
13:29:00 74006 8192000000 10159686064 10634285056 1165153872 12.452 100 18 36 0 1247626144 78584768 3887504
13:31:22 75229 8192000000 10107641824 10605985792 1095091856 11.791 95 8 16 0 1192649600 95505760 2051984
13:33:42 39324 8192000000 10064162000 10490875904 1084555040 11.691 95 30 60 0 1104158608 13631344 5972224
13:35:30 32598 8192000000 10112936992 10569736192 1071157184 11.563 96 36 72 2 1168682768 90322400 7203184
13:37:03 16422 8192000000 9057438080 9303257088 198724848 2.368 18 2 2 1 218310080 19356752 228480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-08-2021 13:38:07 to 05-08-2021 13:38:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-08-2021 13:38:23 to 05-08-2021 13:38:25.

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          238861
MON$OLDEST_ACTIVE               238862
MON$OLDEST_SNAPSHOT             238862
MON$NEXT_TRANSACTION            238862
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                       2053856
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             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2558 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2558 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2558 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2558 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Thu Aug  5 13:37:23 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Thu Aug  5 13:37:25 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Aug  5 13:37:25 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

05.08.21 13:38:26. 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-08-05 13:38:28 No severe PSQL-related problems occured
05.08.21 13:38:29. End of report.