Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.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 15908916 kB
MemAvailable 30614272 kB
Buffers 0 kB
Cached 15784040 kB
SwapCached 3704 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-10-22 13:37:11
DTS_END                         2021-10-22 13:37:11.2750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55680
TRN_ID                          227712
ATT_ID                          608
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: 8558.58 21455 1027030
customer order: creation 161.67 452 19400
customer order: refuse 13.79 381 1655
order to supplier: creation 44.02 1629 5282
order to supplier: removal 3.38 115592 406
invoice (draft): creation 42.99 3787 5159
invoice (draft): removal 48.28 1076 5793
invoice accept: apply 39.23 22049 4708
invoice accept: cancel 43.65 998 5238
customer reserve: creation 3605.61 219 432673
customer reserve: removal 4275.13 71 513015
realization accept: apply 78.76 197 9451
realization accept: cancel 80.80 129 9696
payment from customer: creation 54.76 114 6571
payment from customer: removal 5.04 86 605
payment to supplier: creation 47.28 151 5673
payment to supplier: removal 3.48 98 418
service: total inventory turnovers 3.55 3816 426
service: total monetary turnovers 6.28 559 754
service: aggregate perf. data 0.86 18418 103
service: refresh index statistics 0.03 259271 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2021 13:37:55 to 22-10-2021 13:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5868
TEST_TIME 2 6873
TEST_TIME 3 6172
TEST_TIME 4 6263
TEST_TIME 5 5423
TEST_TIME 6 5212
TEST_TIME 7 4903
TEST_TIME 8 5183
TEST_TIME 9 5231
TEST_TIME 10 5615
TEST_TIME 11 5580
TEST_TIME 12 5839
TEST_TIME 13 5915
TEST_TIME 14 6036
TEST_TIME 15 6836
TEST_TIME 16 7191
TEST_TIME 17 7176
TEST_TIME 18 7396
TEST_TIME 19 7957
TEST_TIME 20 8195
TEST_TIME 21 8421
TEST_TIME 22 8432
TEST_TIME 23 8991
TEST_TIME 24 9299
TEST_TIME 25 9565
TEST_TIME 26 10235
TEST_TIME 27 10383
TEST_TIME 28 10150
TEST_TIME 29 10189
TEST_TIME 30 10132
TEST_TIME 31 9929
TEST_TIME 32 9844
TEST_TIME 33 9735
TEST_TIME 34 9544
TEST_TIME 35 9468
TEST_TIME 36 9304
TEST_TIME 37 9305
TEST_TIME 38 9244
TEST_TIME 39 9093
TEST_TIME 40 8994
TEST_TIME 41 8880
TEST_TIME 42 8825
TEST_TIME 43 8822
TEST_TIME 44 8754
TEST_TIME 45 8740
TEST_TIME 46 8688
TEST_TIME 47 8766
TEST_TIME 48 8746
TEST_TIME 49 8662
TEST_TIME 50 8654
TEST_TIME 51 8805
TEST_TIME 52 8829
TEST_TIME 53 8865
TEST_TIME 54 8892
TEST_TIME 55 8944
TEST_TIME 56 9011
TEST_TIME 57 8991
TEST_TIME 58 8952
TEST_TIME 59 9065
TEST_TIME 60 8981
TEST_TIME 61 8889
TEST_TIME 62 8788
TEST_TIME 63 8771
TEST_TIME 64 8715
TEST_TIME 65 8661
TEST_TIME 66 8618
TEST_TIME 67 8598
TEST_TIME 68 8669
TEST_TIME 69 8621
TEST_TIME 70 8620
TEST_TIME 71 8735
TEST_TIME 72 8677
TEST_TIME 73 8644
TEST_TIME 74 8687
TEST_TIME 75 8737
TEST_TIME 76 8744
TEST_TIME 77 8860
TEST_TIME 78 8833
TEST_TIME 79 8818
TEST_TIME 80 8748
TEST_TIME 81 8755
TEST_TIME 82 8711
TEST_TIME 83 8640
TEST_TIME 84 8555
TEST_TIME 85 8598
TEST_TIME 86 8606
TEST_TIME 87 8602
TEST_TIME 88 8579
TEST_TIME 89 8558
TEST_TIME 90 8541
TEST_TIME 91 8607
TEST_TIME 92 8673
TEST_TIME 93 8655
TEST_TIME 94 8636
TEST_TIME 95 8666
TEST_TIME 96 8691
TEST_TIME 97 8724
TEST_TIME 98 8715
TEST_TIME 99 8775
TEST_TIME 100 8694
TEST_TIME 101 8624
TEST_TIME 102 8666
TEST_TIME 103 8609
TEST_TIME 104 8580
TEST_TIME 105 8553
TEST_TIME 106 8505
TEST_TIME 107 8505
TEST_TIME 108 8486
TEST_TIME 109 8514
TEST_TIME 110 8512
TEST_TIME 111 8483
TEST_TIME 112 8479
TEST_TIME 113 8563
TEST_TIME 114 8553
TEST_TIME 115 8589
TEST_TIME 116 8564
TEST_TIME 117 8609
TEST_TIME 118 8577
TEST_TIME 119 8611
TEST_TIME 120 8562

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2021 13:37:59 to 22-10-2021 13:38:02.

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 1427167 1427167 0 0.00 0 21587 8 0 0
doc_list_biud 1427165 1427165 0 0.00 0 31094 33 0 0
sp_add_invoice_to_stock 4811 4708 103 2.14 34 215250 22049 0 103
sp_cancel_adding_invoice 5238 5238 0 0.00 5 93854 998 0 0
sp_cancel_client_order 1655 1655 0 0.00 5 16365 381 0 0
sp_cancel_customer_reserve 513015 513015 0 0.00 1 23612 72 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 3 7047 86 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 4725 99 0 0
sp_cancel_supplier_invoice 5799 5793 6 0.10 2 31183 1077 0 6
sp_cancel_supplier_order 406 406 0 0.00 8 1077137 115593 0 0
sp_cancel_write_off 9696 9696 0 0.00 1 16463 129 0 0
sp_client_order 19400 19400 0 0.00 4 47108 453 0 0
sp_customer_reserve 432673 432673 0 0.00 0 45453 220 0 0
sp_fill_shopping_cart 29832 29832 0 0.00 1 20140 51 0 0
sp_fill_shopping_cart_clo_res 432666 432666 0 0.00 0 14435 21 0 0
sp_get_clo_for_invoice 4701 4701 0 0.00 0 33142 520 0 0
sp_kill_cost_storno 15957 15957 0 0.00 0 14851 65 0 0
sp_kill_qstorno_ret_qs2qd 1041925 1041925 0 0.00 0 31092 40 0 0
sp_kill_qty_storno 549955 549955 0 0.00 0 31093 85 0 0
sp_lock_selected_doc 19591 19591 0 0.00 0 7194 4 0 0
sp_make_cost_storno 26396 26396 0 0.00 0 21570 67 0 0
sp_multiply_rows_for_pdistr 26396 26396 0 0.00 0 11843 35 0 0
sp_multiply_rows_for_qdistr 19400 19400 0 0.00 0 47098 237 0 0
sp_pay_from_customer 6571 6571 0 0.00 5 15444 115 0 0
sp_pay_to_supplier 5673 5673 0 0.00 5 25059 152 0 0
sp_payment_common 12243 12243 0 0.00 1 15013 23 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 0 16355 350 0 0
sp_qd_handle_on_invoice_upd_sts 9939 9939 0 0.00 0 16400 377 0 0
sp_qd_handle_on_reserve_upd_sts 9451 9451 0 0.00 0 17856 61 0 0
sp_reserve_write_off 9451 9451 0 0.00 6 19143 198 0 0
sp_supplier_invoice 5159 5159 0 0.00 7 67745 3788 0 0
sp_supplier_order 5282 5282 0 0.00 6 44083 1630 0 0
srv_aggregate_perf_data 103 103 0 0.00 3098 180402 18419 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1865 148299 45513 0 0
srv_find_qd_qs_mism 981935 981935 0 0.00 0 14253 7 0 0
srv_make_invnt_saldo 426 426 0 0.00 85 111908 3817 0 0
srv_make_money_saldo 754 754 0 0.00 1 14411 559 0 0
srv_recalc_idx_stat 4 4 0 0.00 107092 406985 259271 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 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 4708 4708 0 0.00 0 11584 188 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 1 3535 110 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 5474 19 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 5068 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 903 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1234 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 339 339 0 0.00 0 1691 17 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 16742 1523 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 1188 4 0 0
t$perf-norm:sp_client_order 19400 19400 0 0.00 0 27154 23 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 8522 15 0 0
t$perf-norm:sp_pay_from_customer 6571 6571 0 0.00 0 8752 11 0 0
t$perf-norm:sp_pay_to_supplier 5673 5673 0 0.00 0 4805 11 0 0
t$perf-norm:sp_reserve_write_off 9451 9451 0 0.00 0 8803 13 0 0
t$perf-norm:sp_supplier_invoice 5159 5159 0 0.00 0 10704 25 0 0
t$perf-norm:sp_supplier_order 5282 5282 0 0.00 0 9222 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 71 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 6951 108 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 1543 16 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 5294 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9608 9402 206 2.14 0 11488 18 0 206
v_all_customers 35450 35450 0 0.00 0 65 1 0 0
v_all_suppliers 28886 28886 0 0.00 0 94 1 0 0
v_all_wares 38800 38800 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1105 8 0 0
v_cancel_client_order 3310 3310 0 0.00 0 3291 7 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 3013 6 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 2272 7 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 4325 10 0 0
v_cancel_supplier_invoice 690 678 12 1.73 0 1259 17 0 12
v_cancel_supplier_order 812 812 0 0.00 0 1616 10 0 0
v_cancel_write_off 1914 1914 0 0.00 0 688 13 0 0
v_max_id_clo_ord 5279 5279 0 0.00 0 891 1 0 0
v_max_id_clo_res 970 970 0 0.00 0 4585 18 0 0
v_max_id_ord_sup 5153 5153 0 0.00 0 5069 5 0 0
v_max_non_paid_invoice 1662 1662 0 0.00 0 6718 6 0 0
v_max_non_paid_realizn 3381 3381 0 0.00 0 5153 4 0 0
v_min_id_clo_ord 5279 5279 0 0.00 0 2755 5 0 0
v_min_id_clo_res 970 970 0 0.00 0 3229 9 0 0
v_min_id_ord_sup 5153 5153 0 0.00 0 6321 10 0 0
v_min_non_paid_invoice 5673 5673 0 0.00 0 15008 19 0 0
v_min_non_paid_realizn 6570 6570 0 0.00 0 7359 13 0 0
v_our_firm 9728 9728 0 0.00 0 19 1 0 0
v_reserve_write_off 18902 18902 0 0.00 0 3397 6 0 0
x_lock_depdocs_on_canc_invoice 10692 10692 0 0.00 0 13624 121 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 10397 347 0 0
x_make_qstorno_1000_1200 5282 5282 0 0.00 2 44074 1547 0 0
x_make_qstorno_1200_2000 5159 5159 0 0.00 4 67693 3583 0 0
x_make_qstorno_2100_3300 432673 432673 0 0.00 0 45451 197 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2021 13:38:03 to 22-10-2021 13:38:07.

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:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 35663 8192000000 9720413088 10017136640 793398096 8.829 100 83 166 0 822074864 7552448 21124320
11:09:53 30104 8192000000 9773405968 10081755136 848272400 9.383 100 93 186 1 881955024 11552448 22130176
11:11:28 15361 8192000000 9825897376 10142883840 881761344 9.717 100 92 184 0 926995472 24475904 20758224
11:12:54 12418 8192000000 9869424496 10203656192 920648384 10.102 100 72 144 0 975203952 38708208 15847360
11:14:08 9590 8192000000 9937803120 10281865216 965381920 10.542 100 78 155 0 1043315984 61601264 16332800
11:15:22 5380 8192000000 9947404592 10305683456 999874656 10.877 100 67 134 0 1061849152 48204480 13770016
11:16:29 3022 8192000000 10011989968 10373120000 1023964464 11.110 100 85 169 0 1126129376 84681648 17483264
11:17:34 1602 8192000000 10015590272 10382864384 1056341776 11.421 100 91 183 0 1135900848 60391280 19167792
11:18:36 3438 8192000000 10034805808 10414653440 1064766832 11.502 100 78 156 0 1146129872 64829696 16533344
11:19:41 8821 8192000000 9980763024 10384240640 1071671632 11.568 100 30 60 1 1105272624 27364928 6236064
11:20:51 5465 8192000000 9994714688 10389868544 1078923376 11.637 100 35 68 1 1108887424 22122672 7841376
11:21:57 5655 8192000000 10006688368 10412683264 1086071888 11.705 100 39 78 2 1134227072 39260960 8894224
11:23:03 18112 8192000000 10008400448 10399973376 1088203536 11.726 100 55 109 0 1110623920 10538912 11881472
11:24:22 4997 8192000000 10031456336 10432483328 1096262400 11.802 100 46 91 2 1145647712 39773504 9611808
11:25:29 3251 8192000000 10018270592 10417872896 1098296112 11.821 100 32 62 2 1134339872 28689296 7354464
11:26:33 59675 8192000000 9996280768 10403241984 1079755872 11.645 99 36 72 0 1105556576 18038144 7762560
11:28:33 1904 8192000000 10011304752 10394869760 1065357856 11.508 100 63 127 1 1116148336 37269776 13520704
11:29:35 6179 8192000000 9936922624 10320539648 969187248 10.579 93 48 97 2 1005808112 26141568 10479296
11:30:43 9079 8192000000 9941484240 10338299904 1011246544 10.987 98 30 60 0 1046931136 28976752 6707840
11:31:53 28871 8192000000 9912651872 10307608576 957295632 10.463 93 30 58 2 982816144 18859824 6660688
11:33:22 3408 8192000000 9962391552 10339246080 1030853808 11.177 99 61 123 3 1059368608 15172048 13342752
11:34:27 7089 8192000000 9973708144 10373398528 1054878976 11.407 100 39 74 2 1075025600 12123360 8023264
11:35:37 4278 8192000000 9992262896 10384486400 1065978464 11.514 100 41 82 0 1096444752 21119664 9346624
11:37:32 11980 8192000000 10011815680 10401878016 1094549584 11.786 100 61 122 1 1120549984 14000160 12000240
11:38:49 6298 8192000000 10111975024 10508361728 1112303760 11.954 100 62 124 1 1216920768 92660064 11956944
11:39:59 28960 8192000000 10099368160 10503397376 1127560752 12.098 100 53 100 3 1198858272 60494080 10803440
11:41:42 12808 8192000000 10149359472 10552463360 1145202896 12.264 100 46 91 3 1243732832 87872752 10657184
11:42:58 21871 8192000000 10181777248 10597404672 1150467344 12.314 100 38 76 0 1306304464 146996784 8840336
11:44:25 11630 8192000000 10241781376 10653380608 1158942736 12.393 100 58 115 0 1345061136 173330800 12787600
11:45:46 13231 8192000000 10247161248 10660040704 1148702192 12.297 99 54 108 1 1343998688 182192992 13103504
11:47:06 9599 8192000000 10289256928 10708357120 1153929408 12.346 99 55 108 1 1389173200 222329024 12914768
11:48:20 3494 8192000000 10321261920 10726506496 1166254576 12.462 100 66 131 4 1418361120 235793504 16313040
11:49:32 2846 8192000000 10343786288 10748448768 1186955808 12.655 100 90 178 1 1446355920 239393488 20006624
11:50:43 2871 8192000000 10375713056 10789134336 1181245344 12.602 100 67 134 1 1480271104 283299360 15726400
11:51:49 5359 8192000000 10418948000 10832424960 1178779520 12.579 100 77 152 2 1513972192 316791408 18401264
11:53:01 1863 8192000000 10393469152 10796134400 1168460448 12.482 100 87 173 2 1491850384 303610272 19779664
11:54:07 6898 8192000000 10431107792 10848309248 1176713664 12.560 100 72 141 0 1524653792 331459568 16480560
11:55:20 2183 8192000000 10435164880 10862231552 1164751024 12.448 100 75 150 0 1535738496 352444928 18542544
11:56:31 6964 8192000000 10345476448 10778431488 1143092496 12.245 99 32 64 0 1440281824 288623104 8566224
11:57:48 7289 8192000000 10360241040 10779811840 1157038080 12.376 100 46 93 0 1452304768 284490032 10776656
11:58:57 21234 8192000000 10357133632 10790981632 1140908048 12.224 99 27 54 0 1439317616 290843920 7565648
12:00:24 28192 8192000000 10306660176 10802167808 1139326208 12.209 100 22 44 0 1452740176 307230496 6183472
12:01:57 18881 8192000000 10248235168 10688200704 1098708800 11.825 97 19 38 0 1332990112 229104816 5176496
12:03:18 30296 8192000000 10117393344 10636554240 1121997600 12.046 100 21 43 2 1307939728 180738288 5203840
12:04:51 50135 8192000000 10099582032 10521288704 1100641872 11.844 98 52 102 3 1170082160 58479264 10961024
12:06:56 67178 8192000000 10052424224 10512318464 1108047696 11.914 99 14 28 0 1162087456 51041168 2998592
12:09:16 62654 8192000000 10025502976 10464104448 1059042704 11.447 94 23 46 1 1101419072 37427552 4948816
12:11:21 43902 8192000000 10032806096 10456883200 1098761760 11.826 99 21 41 0 1133253904 29112832 5379312
12:13:06 129232 8192000000 10043869376 10495447040 1099522064 11.833 98 28 55 1 1164190608 58889392 5779152
12:16:18 54856 8192000000 10050716400 10476011520 1117811264 12.006 100 34 68 0 1148655216 23775344 7068608
12:18:32 28798 8192000000 10115965872 10545844224 1133420672 12.154 100 44 88 0 1197132704 52822864 10889168
12:20:16 39551 8192000000 10129248384 10585038848 1126662224 12.090 99 26 51 2 1241398256 109144544 5591488
12:22:00 76369 8192000000 10109431168 10595917824 1131598896 12.136 100 15 30 0 1245873664 110487296 3787472
12:24:23 56006 8192000000 10052154928 10512859136 1123873552 12.064 99 11 22 1 1169036544 42869184 2293808
12:26:23 81897 8192000000 10134696992 10601844736 1140835776 12.223 100 38 76 1 1212887904 63450848 8601280
12:28:45 57795 8192000000 10107649440 10584047616 1141953552 12.234 100 23 46 1 1199109088 51871520 5284016
12:30:51 43159 8192000000 10112063984 10548174848 1144636208 12.259 100 26 50 1 1200741664 51045792 5059664
12:32:35 148299 8192000000 10088799520 10531831808 1111885392 11.950 97 8 16 1 1160127344 46615712 1626240
12:36:04 54895 8192000000 10090897792 10580832256 1127780288 12.100 99 28 56 0 1216679184 83353296 5545600
12:37:59 54852 8192000000 10086415104 10505093120 1138599840 12.202 99 39 77 1 1169388080 21388352 9399888
12:39:54 10368 8192000000 10073610384 10498396160 1125673664 12.081 99 47 94 1 1167285664 31643552 9968448
12:41:05 60938 8192000000 10069266512 10517958656 1130718128 12.128 99 8 16 1 1168211872 36099440 1394304
12:43:07 38024 8192000000 10081066672 10521337856 1128717344 12.109 99 11 22 0 1185968976 55020448 2231184
12:44:45 56207 8192000000 10111309472 10548813824 1099568208 11.834 97 10 20 0 1174351008 72604672 2178128
12:46:42 37708 8192000000 10094955120 10511880192 1090538704 11.748 97 43 83 0 1154819152 55140448 9140000
12:48:22 47500 8192000000 10035999312 10538631168 1092154096 11.763 99 10 20 0 1181935520 87702944 2078480
12:50:14 33478 8192000000 10075351696 10499575808 1080095152 11.648 97 27 54 0 1132642624 47332352 5215120
12:51:48 55253 8192000000 10071180144 10518310912 1084203664 11.688 97 28 56 0 1143322608 53612016 5506928
12:53:44 66287 8192000000 10077319376 10603958272 1085936528 11.704 97 23 46 1 1179290976 88719712 4634736
12:55:56 55076 8192000000 10116836752 10564804608 1098393200 11.822 98 40 80 2 1165394688 58947968 8053520
12:57:58 114478 8192000000 10053677088 10506764288 1069987440 11.552 96 25 50 0 1125338800 50459008 4892352
13:00:53 68868 8192000000 10082459648 10526478336 1098714096 11.825 98 12 24 0 1179081120 77410992 2956032
13:03:25 78491 8192000000 10047517152 10490253312 1085392640 11.699 97 14 30 0 1119024528 30425632 3206256
13:05:44 88499 8192000000 10121488128 10556514304 1117807440 12.006 98 19 38 1 1193028080 71352288 3868352
13:08:13 53129 8192000000 10104209776 10614816768 1118094048 12.009 98 16 32 1 1215017488 93392256 3531184
13:10:29 38177 8192000000 10076769472 10503598080 1114559088 11.976 98 45 87 2 1141763520 18250016 8954416
13:12:13 29893 8192000000 10096250016 10555404288 1110479584 11.937 98 39 78 1 1192089968 73729376 7881008
13:14:10 30254 8192000000 10090940528 10549100544 1125664272 12.080 99 38 76 0 1175746320 42518000 7564048
13:16:04 115331 8192000000 10038787040 10490466304 1108969856 11.923 97 10 20 0 1158426608 47451600 2005152
13:19:36 74345 8192000000 10082802016 10576330752 1144526176 12.258 100 15 30 0 1232121088 84526720 3068192
13:21:50 53379 8192000000 10076061424 10555625472 1136508352 12.183 99 11 22 0 1184977760 45966432 2502976
13:23:45 109265 8192000000 10119288560 10613403648 1153560912 12.343 100 16 30 1 1212910736 56134752 3215072
13:26:36 53061 8192000000 10129076688 10577207296 1159170912 12.395 100 20 41 1 1219773360 56384464 4217984
13:28:31 87528 8192000000 10172512448 10635362304 1166444448 12.464 100 18 36 0 1242476864 72144016 3888400
13:31:01 86069 8192000000 10105469184 10566172672 1110200240 11.934 96 18 36 1 1157902768 43874640 3827888
13:33:27 63281 8192000000 10124926128 10578395136 1137326672 12.190 98 39 74 1 1205273792 60008704 7938416
13:35:33 19102 8192000000 10096135552 10526253056 1110506160 11.937 98 35 70 0 1167610656 50323424 6781072
13:36:54 29887 8192000000 9034164272 9269395456 190276624 2.269 17 2 4 0 203289104 12784240 228240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-10-2021 13:38:09 to 22-10-2021 13:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 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 22-10-2021 13:38:25 to 22-10-2021 13:38:27.

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          228023
MON$OLDEST_ACTIVE               228024
MON$OLDEST_SNAPSHOT             228024
MON$NEXT_TRANSACTION            228024
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                       2032864
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2637 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2637 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2637 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2637 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

22.10.21 13:38:28. 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-10-22 13:38:31 No severe PSQL-related problems occured
22.10.21 13:38:32. End of report.