Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2021 04: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 16028072 kB
MemAvailable 30946512 kB
Buffers 20 kB
Cached 15553124 kB
SwapCached 2596 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-12 04:37:05
DTS_END                         2021-08-12 04:37:05.3720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(139:30) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38418
TRN_ID                          235056
ATT_ID                          704
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: 9268.01 22933 1112161
customer order: creation 166.15 398 19938
customer order: refuse 14.55 327 1746
order to supplier: creation 44.03 1494 5284
order to supplier: removal 3.64 104629 437
invoice (draft): creation 44.36 3437 5323
invoice (draft): removal 53.93 923 6471
invoice accept: apply 39.98 20650 4798
invoice accept: cancel 49.23 941 5908
customer reserve: creation 3716.19 204 445943
customer reserve: removal 4836.49 62 580379
realization accept: apply 81.28 183 9754
realization accept: cancel 91.88 110 11026
payment from customer: creation 58.25 122 6990
payment from customer: removal 5.53 98 664
payment to supplier: creation 48.39 123 5807
payment to supplier: removal 3.33 81 400
service: total inventory turnovers 3.75 3568 450
service: total monetary turnovers 6.18 601 741
service: aggregate perf. data 0.81 21681 97
service: refresh index statistics 0.04 299034 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-08-2021 04:37:48 to 12-08-2021 04:37:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7411
TEST_TIME 2 6855
TEST_TIME 3 6380
TEST_TIME 4 6267
TEST_TIME 5 6233
TEST_TIME 6 6072
TEST_TIME 7 6009
TEST_TIME 8 6427
TEST_TIME 9 6387
TEST_TIME 10 6472
TEST_TIME 11 6522
TEST_TIME 12 6773
TEST_TIME 13 6861
TEST_TIME 14 7066
TEST_TIME 15 7082
TEST_TIME 16 7334
TEST_TIME 17 7836
TEST_TIME 18 7810
TEST_TIME 19 8274
TEST_TIME 20 8579
TEST_TIME 21 9045
TEST_TIME 22 10015
TEST_TIME 23 10027
TEST_TIME 24 10236
TEST_TIME 25 10514
TEST_TIME 26 10648
TEST_TIME 27 11123
TEST_TIME 28 10989
TEST_TIME 29 11262
TEST_TIME 30 11192
TEST_TIME 31 11213
TEST_TIME 32 10997
TEST_TIME 33 10768
TEST_TIME 34 10472
TEST_TIME 35 10369
TEST_TIME 36 10290
TEST_TIME 37 10362
TEST_TIME 38 10346
TEST_TIME 39 10489
TEST_TIME 40 10328
TEST_TIME 41 10196
TEST_TIME 42 10214
TEST_TIME 43 10205
TEST_TIME 44 10081
TEST_TIME 45 10065
TEST_TIME 46 10091
TEST_TIME 47 10113
TEST_TIME 48 10094
TEST_TIME 49 10141
TEST_TIME 50 10073
TEST_TIME 51 10086
TEST_TIME 52 10045
TEST_TIME 53 10300
TEST_TIME 54 10324
TEST_TIME 55 10298
TEST_TIME 56 10202
TEST_TIME 57 10086
TEST_TIME 58 10016
TEST_TIME 59 10046
TEST_TIME 60 9952
TEST_TIME 61 9898
TEST_TIME 62 9814
TEST_TIME 63 9797
TEST_TIME 64 9784
TEST_TIME 65 9729
TEST_TIME 66 9721
TEST_TIME 67 9672
TEST_TIME 68 9620
TEST_TIME 69 9642
TEST_TIME 70 9715
TEST_TIME 71 9653
TEST_TIME 72 9712
TEST_TIME 73 9669
TEST_TIME 74 9619
TEST_TIME 75 9762
TEST_TIME 76 9748
TEST_TIME 77 9761
TEST_TIME 78 9791
TEST_TIME 79 9771
TEST_TIME 80 9683
TEST_TIME 81 9597
TEST_TIME 82 9571
TEST_TIME 83 9590
TEST_TIME 84 9520
TEST_TIME 85 9466
TEST_TIME 86 9420
TEST_TIME 87 9410
TEST_TIME 88 9474
TEST_TIME 89 9493
TEST_TIME 90 9471
TEST_TIME 91 9457
TEST_TIME 92 9467
TEST_TIME 93 9474
TEST_TIME 94 9464
TEST_TIME 95 9503
TEST_TIME 96 9551
TEST_TIME 97 9573
TEST_TIME 98 9557
TEST_TIME 99 9542
TEST_TIME 100 9567
TEST_TIME 101 9603
TEST_TIME 102 9512
TEST_TIME 103 9473
TEST_TIME 104 9463
TEST_TIME 105 9441
TEST_TIME 106 9378
TEST_TIME 107 9328
TEST_TIME 108 9323
TEST_TIME 109 9258
TEST_TIME 110 9225
TEST_TIME 111 9343
TEST_TIME 112 9353
TEST_TIME 113 9343
TEST_TIME 114 9297
TEST_TIME 115 9305
TEST_TIME 116 9332
TEST_TIME 117 9323
TEST_TIME 118 9314
TEST_TIME 119 9340
TEST_TIME 120 9269

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2021 04:37:52 to 12-08-2021 04:37:55.

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 1527017 1527017 0 0.00 0 18109 8 0 0
doc_list_biud 1527017 1527017 0 0.00 0 118354 30 0 0
sp_add_invoice_to_stock 4925 4798 127 2.57 33 252239 20651 0 127
sp_cancel_adding_invoice 5908 5908 0 0.00 3 73232 942 0 0
sp_cancel_client_order 1746 1746 0 0.00 4 9566 328 0 0
sp_cancel_customer_reserve 580379 580379 0 0.00 1 29285 62 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 2 4884 98 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 2847 81 0 0
sp_cancel_supplier_invoice 6482 6471 11 0.16 1 118359 924 0 11
sp_cancel_supplier_order 437 437 0 0.00 9 1159649 104629 0 0
sp_cancel_write_off 11026 11026 0 0.00 1 24793 110 0 0
sp_client_order 19938 19938 0 0.00 4 28318 399 0 0
sp_customer_reserve 445943 445943 0 0.00 0 74691 205 0 0
sp_fill_shopping_cart 30544 30544 0 0.00 1 15906 41 0 0
sp_fill_shopping_cart_clo_res 445940 445940 0 0.00 0 14669 14 0 0
sp_get_clo_for_invoice 4793 4793 0 0.00 0 27106 334 0 0
sp_kill_cost_storno 17998 17998 0 0.00 0 8091 57 0 0
sp_kill_qstorno_ret_qs2qd 1178692 1178692 0 0.00 0 118353 34 0 0
sp_kill_qty_storno 620514 620514 0 0.00 0 118354 73 0 0
sp_lock_selected_doc 20277 20277 0 0.00 0 9466 4 0 0
sp_make_cost_storno 27344 27344 0 0.00 0 17317 56 0 0
sp_multiply_rows_for_pdistr 27344 27344 0 0.00 0 9472 34 0 0
sp_multiply_rows_for_qdistr 19938 19938 0 0.00 0 20950 183 0 0
sp_pay_from_customer 6990 6990 0 0.00 4 15456 123 0 0
sp_pay_to_supplier 5807 5807 0 0.00 5 15949 124 0 0
sp_payment_common 12797 12797 0 0.00 1 12220 24 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 9528 298 0 0
sp_qd_handle_on_invoice_upd_sts 10701 10701 0 0.00 0 36382 377 0 0
sp_qd_handle_on_reserve_upd_sts 9754 9754 0 0.00 0 21189 53 0 0
sp_reserve_write_off 9754 9754 0 0.00 7 26821 184 0 0
sp_supplier_invoice 5323 5323 0 0.00 6 126688 3437 0 0
sp_supplier_order 5284 5284 0 0.00 6 88623 1494 0 0
srv_aggregate_perf_data 97 97 0 0.00 3038 517951 21682 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2707 154212 44529 0 0
srv_find_qd_qs_mism 1066837 1066837 0 0.00 0 18108 7 0 0
srv_make_invnt_saldo 450 450 0 0.00 60 88119 3568 0 0
srv_make_money_saldo 741 741 0 0.00 1 15684 602 0 0
srv_recalc_idx_stat 5 5 0 0.00 154158 542737 299034 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4798 4798 0 0.00 0 15636 194 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 3251 119 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 6636 22 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 4868 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 2972 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 850 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 943 16 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 23501 1399 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 2425 7 0 0
t$perf-norm:sp_client_order 19938 19938 0 0.00 0 6223 20 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 962 5 0 0
t$perf-norm:sp_pay_from_customer 6990 6990 0 0.00 0 4594 8 0 0
t$perf-norm:sp_pay_to_supplier 5807 5807 0 0.00 0 6253 11 0 0
t$perf-norm:sp_reserve_write_off 9754 9754 0 0.00 0 6295 9 0 0
t$perf-norm:sp_supplier_invoice 5323 5323 0 0.00 0 5692 18 0 0
t$perf-norm:sp_supplier_order 5284 5284 0 0.00 0 3032 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 1077 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 966 23 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 1601 13 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 3130 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9836 9582 254 2.58 0 6058 16 0 254
v_all_customers 36790 36790 0 0.00 0 18 1 0 0
v_all_suppliers 29824 29824 0 0.00 0 98 1 0 0
v_all_wares 39876 39876 0 0.00 0 80 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 193 5 0 0
v_cancel_client_order 3492 3492 0 0.00 0 6121 7 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 4330 16 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 1214 4 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2395 9 0 0
v_cancel_supplier_invoice 792 770 22 2.77 0 1667 14 0 22
v_cancel_supplier_order 874 874 0 0.00 0 3389 13 0 0
v_cancel_write_off 1964 1964 0 0.00 0 4421 16 0 0
v_max_id_clo_ord 5284 5284 0 0.00 0 1130 2 0 0
v_max_id_clo_res 943 943 0 0.00 0 3765 15 0 0
v_max_id_ord_sup 5322 5322 0 0.00 0 7092 4 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 782 3 0 0
v_max_non_paid_realizn 3502 3502 0 0.00 0 5800 6 0 0
v_min_id_clo_ord 5284 5284 0 0.00 0 4516 8 0 0
v_min_id_clo_res 943 943 0 0.00 0 1925 10 0 0
v_min_id_ord_sup 5322 5322 0 0.00 0 6534 7 0 0
v_min_non_paid_invoice 5807 5807 0 0.00 0 8134 12 0 0
v_min_non_paid_realizn 6990 6990 0 0.00 0 9011 22 0 0
v_our_firm 10062 10062 0 0.00 0 19 1 0 0
v_reserve_write_off 19508 19508 0 0.00 0 5239 6 0 0
x_lock_depdocs_on_canc_invoice 11994 11994 0 0.00 0 10616 105 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 6728 337 0 0
x_make_qstorno_1000_1200 5284 5284 0 0.00 2 88585 1414 0 0
x_make_qstorno_1200_2000 5323 5323 0 0.00 3 126677 3285 0 0
x_make_qstorno_2100_3300 445943 445943 0 0.00 0 74690 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2021 04:37:56 to 12-08-2021 04:38:00.

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
02:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:10 30227 8192000000 9718877888 10015813632 793338448 8.829 100 83 166 0 822214576 8008048 20868080
02:09:53 8637 8192000000 9760957408 10068586496 834364464 9.243 100 85 170 0 867576896 13029776 20182656
02:11:17 9375 8192000000 9798054480 10119413760 868781776 9.588 100 79 158 0 910144624 23461536 17901312
02:12:51 9089 8192000000 9878393488 10216501248 922105344 10.117 100 83 164 3 987772512 48326432 17340736
02:14:03 5210 8192000000 9940026208 10290208768 975050880 10.636 100 86 172 1 1055270592 62348528 17871184
02:15:09 2984 8192000000 10000543248 10360537088 1008718096 10.963 100 84 165 1 1115055152 89529200 16807856
02:16:13 3163 8192000000 10014046288 10378006528 1029551376 11.164 100 82 164 0 1123047680 76924128 16572176
02:17:17 1387 8192000000 10031165664 10403921920 1059553984 11.452 100 90 175 2 1143784048 65274896 18955168
02:18:20 10264 8192000000 10032140720 10415882240 1071143776 11.563 100 69 140 2 1149775376 64851568 13780032
02:19:31 6210 8192000000 9991374608 10385268736 1076751072 11.617 100 45 90 1 1108799456 23730752 8317632
02:20:37 5245 8192000000 9987367856 10395996160 1087266448 11.717 100 42 84 2 1117194240 21084192 8843600
02:21:47 5557 8192000000 10013411792 10409172992 1089539888 11.738 100 41 80 0 1124006144 25664736 8801520
02:22:57 46535 8192000000 10034575440 10423640064 1095440704 11.794 100 63 126 0 1130489824 21496288 13552832
02:24:44 4032 8192000000 10032988256 10436452352 1102210896 11.859 100 53 105 1 1152517776 38478544 11828336
02:25:49 10039 8192000000 10013423744 10418434048 1084033616 11.686 98 31 62 0 1116305424 25055072 7216736
02:27:04 60848 8192000000 9977469984 10373279744 1021115872 11.083 94 38 76 2 1055150336 25796560 8237904
02:29:07 3977 8192000000 9946373136 10330824704 1017284256 11.046 98 52 103 1 1044504656 14980672 12239728
02:30:12 7871 8192000000 9960926720 10352455680 1044049200 11.304 100 40 77 3 1068866368 15116704 9700464
02:31:21 8024 8192000000 9945404448 10322980864 1025122752 11.121 99 32 65 1 1053939248 21620032 7196464
02:32:31 14860 8192000000 9937054208 10334724096 1011612416 10.991 97 16 32 1 1035016736 20116032 3288288
02:33:47 8926 8192000000 9979320448 10379468800 1062145920 11.477 100 50 96 4 1085308880 11674704 11488256
02:34:58 4406 8192000000 9986114608 10370613248 1070396464 11.556 100 40 78 5 1089260992 9591536 9272992
02:36:03 68155 8192000000 9995121968 10393100288 1077285632 11.622 100 36 72 2 1099730272 14197760 8246880
02:38:23 34521 8192000000 10056338624 10461429760 1110354560 11.936 100 55 110 2 1164822432 42660528 11807344
02:40:02 27841 8192000000 10079596256 10489679872 1122854320 12.054 100 52 104 1 1189410352 54998560 11557472
02:41:33 9497 8192000000 10127653808 10541879296 1143056448 12.244 100 57 114 1 1225245456 70525184 11663824
02:42:46 13107 8192000000 10164663760 10575962112 1152204720 12.330 100 55 109 0 1265644352 101394576 12045056
02:44:01 7229 8192000000 10178494000 10587828224 1150709440 12.316 100 48 95 2 1283665968 123046208 9910320
02:45:14 5948 8192000000 10236281808 10647080960 1166085904 12.460 100 63 125 0 1336753120 156620192 14047024
02:46:23 8426 8192000000 10259458304 10673651712 1167716672 12.475 100 60 119 2 1361993216 180964608 13311936
02:47:38 3190 8192000000 10298185616 10700640256 1178709568 12.578 100 76 147 2 1398840304 202959360 17171376
02:48:43 3793 8192000000 10328408288 10732912640 1149889792 12.308 98 75 149 1 1413770000 247342608 16537600
02:49:54 4123 8192000000 10330681120 10731175936 1147543584 12.286 99 73 143 0 1428712288 265001456 16167248
02:51:09 6224 8192000000 10386717168 10792742912 1165148736 12.451 100 76 151 3 1487103424 304275456 17679232
02:52:19 4092 8192000000 10381944384 10785091584 1140820016 12.223 99 67 134 1 1480124000 323654256 15649728
02:53:30 2706 8192000000 10354604784 10760650752 1116842288 11.997 99 59 114 4 1454257312 324093424 13321600
02:54:36 7382 8192000000 10400786736 10822287360 1142628960 12.240 100 37 74 3 1496105872 343604960 9871952
02:55:53 3472 8192000000 10422613584 10832592896 1144501088 12.258 100 71 141 0 1521680960 360434464 16745408
02:57:02 8935 8192000000 10403588608 10811150336 1116611104 11.995 99 66 130 0 1499009232 366397520 16000608
02:58:13 20503 8192000000 10352527664 10811981824 1138935312 12.206 100 40 80 1 1491701760 342354832 10411616
02:59:39 18420 8192000000 10268232896 10735210496 1132491280 12.145 99 31 62 1 1380746400 240411936 7843184
03:01:08 35695 8192000000 10234389936 10705022976 1149801088 12.308 100 22 44 0 1316944848 161470864 5672896
03:02:49 24676 8192000000 10209884528 10681450496 1134021344 12.159 99 14 28 0 1306612624 169006896 3584384
03:04:23 57592 8192000000 10123820448 10632642560 1103453088 11.870 97 12 24 0 1219302768 113102192 2747488
03:06:46 35428 8192000000 10078221456 10512302080 1071190336 11.563 95 12 23 1 1107723824 34013712 2519776
03:08:24 39314 8192000000 10098606176 10538917888 1134047440 12.160 100 25 37 1 1216826768 78509648 4269680
03:10:04 154211 8192000000 10172979424 10619236352 1134116128 12.160 99 46 92 1 1255674832 111792480 9766224
03:13:39 28483 8192000000 10191933296 10629713920 1148369264 12.294 100 39 78 0 1272741104 115608064 8763776
03:15:08 14035 8192000000 10144475408 10606333952 1156279552 12.368 100 24 48 0 1253873936 92366208 5228176
03:16:25 90652 8192000000 10146158752 10609672192 1161626176 12.418 100 14 28 0 1215624800 51075056 2923568
03:18:56 58223 8192000000 10184084640 10651734016 1165076656 12.451 100 19 38 1 1276606352 106123264 5406432
03:20:57 59319 8192000000 10167885152 10635702272 1143643808 12.250 98 13 26 0 1234688576 88037168 3007600
03:23:27 86166 8192000000 10131943728 10620698624 1143520080 12.249 99 9 18 1 1217632336 72274800 1837456
03:25:54 32801 8192000000 10112667840 10552143872 1162847504 12.430 100 29 56 1 1184362016 15402480 6112032
03:27:27 79800 8192000000 10154231856 10627596288 1131190304 12.133 97 20 40 3 1200516256 64962000 4363952
03:29:50 88160 8192000000 10119690496 10726846464 1149327760 12.303 99 15 30 0 1307926864 155220064 3379040
03:32:25 105095 8192000000 10115236848 10594631680 1140792752 12.223 98 35 70 1 1224444448 76150624 7501072
03:35:28 27431 8192000000 10125460784 10565705728 1152060832 12.329 100 43 86 0 1217379648 56889248 8429568
03:36:57 49919 8192000000 10079952656 10529964032 1119939200 12.026 97 9 18 0 1147609872 26153168 1517504
03:38:55 62599 8192000000 10113518880 10562555904 1134257136 12.161 98 13 26 1 1199780416 62861760 2661520
03:41:02 79565 8192000000 10120299216 10610065408 1137187328 12.189 98 16 32 1 1198426016 57541936 3696752
03:43:22 22128 8192000000 10057114768 10532225024 1123891616 12.064 99 26 52 1 1164198240 35327760 4978864
03:44:50 46741 8192000000 10064739168 10510082048 1070458256 11.556 95 14 28 1 1128243776 55032240 2753280
03:46:42 44059 8192000000 10065947152 10523807744 1055144192 11.410 95 20 40 1 1126492192 67108064 4239936
03:48:31 20894 8192000000 10070374704 10490273792 1080838144 11.655 97 51 102 1 1143470416 52605712 10026560
03:49:58 42393 8192000000 10062736912 10495455232 1099750144 11.835 99 21 42 1 1150963872 47046256 4167472
03:51:53 54202 8192000000 10056890080 10545074176 1091737840 11.759 98 17 34 0 1197587952 102245280 3604832
03:53:49 23339 8192000000 10057313136 10481061888 1095149968 11.792 98 41 82 1 1137538144 34129344 8258832
03:55:15 53885 8192000000 10063564096 10514718720 1074913552 11.599 96 23 46 0 1125834688 46365872 4555264
03:57:09 93976 8192000000 10056227472 10502656000 1078166544 11.630 97 20 40 0 1122472688 39697008 4609136
03:59:44 86769 8192000000 10061856416 10549067776 1109098144 11.924 99 15 30 0 1161562608 49573920 2890544
04:02:11 67866 8192000000 10123795344 10584911872 1130699376 12.128 100 13 26 1 1199934688 66183792 3051520
04:04:35 107707 8192000000 10103990352 10611388416 1124382176 12.068 99 14 28 1 1212915520 85633328 2900016
04:07:37 21355 8192000000 10059170800 10494185472 1109620368 11.929 98 25 50 0 1132041808 16972224 5449216
04:09:00 68848 8192000000 10102693440 10551971840 1130602720 12.127 99 30 60 0 1187392192 50862192 5927280
04:11:09 65614 8192000000 10098267568 10594254848 1118992096 12.017 98 22 43 0 1178202688 54774448 4436144
04:13:25 44028 8192000000 10087986480 10615455744 1139710656 12.213 99 16 32 1 1232968304 89989712 3267936
04:15:10 26345 8192000000 10083716880 10525294592 1132364736 12.144 98 40 80 1 1162102912 20950800 8787376
04:16:48 44911 8192000000 10122478752 10593226752 1131808768 12.138 98 29 58 1 1178313728 40767936 5737024
04:18:37 108528 8192000000 10093966912 10555367424 1139814560 12.214 99 27 54 0 1188507984 42764256 5929168
04:21:26 30117 8192000000 10080902960 10544463872 1145715104 12.269 99 28 56 1 1193249712 41484064 6050544
04:22:58 74344 8192000000 10122849328 10583547904 1159073696 12.395 100 17 34 0 1213304448 50373824 3856928
04:25:13 60816 8192000000 10165098896 10651271168 1153164320 12.339 99 17 34 2 1268926704 111566080 4196304
04:27:51 61141 8192000000 10101474320 10661437440 1141281584 12.228 99 13 26 1 1254437728 110113056 3043088
04:30:02 87732 8192000000 10066376640 10514718720 1126045344 12.084 98 15 30 0 1155398416 26335408 3017664
04:32:32 82364 8192000000 10041730464 10495332352 1034030624 11.207 90 27 54 0 1101598320 62061312 5506384
04:35:03 13398 8192000000 10040730432 10465054720 1057063072 11.428 95 49 98 1 1109639040 42507472 10068496
04:36:17 18653 8192000000 10084484576 10531368960 1090274944 11.745 99 48 96 1 1160722320 61092368 9355008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-08-2021 04:38:02 to 12-08-2021 04:38:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2021 04:38:18 to 12-08-2021 04:38:20.

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          229928
MON$OLDEST_ACTIVE               235194
MON$OLDEST_SNAPSHOT             235194
MON$NEXT_TRANSACTION            235194
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                       2052512
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             805
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2567 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2567 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2567 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 ---
:105: 
:106: mscs-test-161.budzdorov.ru    Thu Aug 12 04:37:11 2021
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
--- end of diff output ---

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

12.08.21 04:38:21. 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-12 04:38:24 No severe PSQL-related problems occured
12.08.21 04:38:25. End of report.