Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15479484 kB
MemAvailable 30482368 kB
Buffers 0 kB
Cached 16209984 kB
SwapCached 2168 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.3
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 2022-10-14 10:37:59
DTS_END                         2022-10-14 10:37:22.8560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50614
TRN_ID                          208481
ATT_ID                          259
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8704.75 23710 1044570
customer order: creation 158.39 528 19007
customer order: refuse 13.13 435 1575
order to supplier: creation 42.14 1476 5057
order to supplier: removal 3.47 186162 416
invoice (draft): creation 41.80 3248 5016
invoice (draft): removal 49.73 1136 5968
invoice accept: apply 38.09 30025 4571
invoice accept: cancel 44.23 1311 5307
customer reserve: creation 3605.57 282 432668
customer reserve: removal 4444.08 112 533289
realization accept: apply 76.14 300 9137
realization accept: cancel 69.93 180 8391
payment from customer: creation 52.53 183 6303
payment from customer: removal 4.95 168 594
payment to supplier: creation 45.93 245 5511
payment to supplier: removal 3.10 130 372
service: total inventory turnovers 3.64 4071 437
service: total monetary turnovers 7.13 376 855
service: aggregate perf. data 0.77 16986 92
service: refresh index statistics 0.03 226839 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2022 10:38:41 to 14-10-2022 10:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8869
TEST_TIME 2 7295
TEST_TIME 3 6254
TEST_TIME 4 5979
TEST_TIME 5 5727
TEST_TIME 6 5364
TEST_TIME 7 6025
TEST_TIME 8 5801
TEST_TIME 9 5884
TEST_TIME 10 5990
TEST_TIME 11 6124
TEST_TIME 12 6384
TEST_TIME 13 6304
TEST_TIME 14 6270
TEST_TIME 15 6454
TEST_TIME 16 6411
TEST_TIME 17 6312
TEST_TIME 18 6426
TEST_TIME 19 6507
TEST_TIME 20 6568
TEST_TIME 21 6605
TEST_TIME 22 6620
TEST_TIME 23 7113
TEST_TIME 24 7288
TEST_TIME 25 7826
TEST_TIME 26 7876
TEST_TIME 27 8919
TEST_TIME 28 8738
TEST_TIME 29 8561
TEST_TIME 30 8850
TEST_TIME 31 8731
TEST_TIME 32 9200
TEST_TIME 33 9575
TEST_TIME 34 10251
TEST_TIME 35 10311
TEST_TIME 36 10527
TEST_TIME 37 10422
TEST_TIME 38 10190
TEST_TIME 39 10306
TEST_TIME 40 10081
TEST_TIME 41 9927
TEST_TIME 42 9826
TEST_TIME 43 9758
TEST_TIME 44 9706
TEST_TIME 45 9636
TEST_TIME 46 9663
TEST_TIME 47 9594
TEST_TIME 48 9497
TEST_TIME 49 9455
TEST_TIME 50 9547
TEST_TIME 51 9473
TEST_TIME 52 9556
TEST_TIME 53 9540
TEST_TIME 54 9613
TEST_TIME 55 9533
TEST_TIME 56 9544
TEST_TIME 57 9626
TEST_TIME 58 9585
TEST_TIME 59 9532
TEST_TIME 60 9580
TEST_TIME 61 9617
TEST_TIME 62 9593
TEST_TIME 63 9605
TEST_TIME 64 9480
TEST_TIME 65 9473
TEST_TIME 66 9388
TEST_TIME 67 9393
TEST_TIME 68 9342
TEST_TIME 69 9274
TEST_TIME 70 9197
TEST_TIME 71 9172
TEST_TIME 72 9109
TEST_TIME 73 9108
TEST_TIME 74 9071
TEST_TIME 75 9074
TEST_TIME 76 9052
TEST_TIME 77 9054
TEST_TIME 78 9086
TEST_TIME 79 9075
TEST_TIME 80 9057
TEST_TIME 81 9014
TEST_TIME 82 9114
TEST_TIME 83 9138
TEST_TIME 84 9263
TEST_TIME 85 9242
TEST_TIME 86 9184
TEST_TIME 87 9186
TEST_TIME 88 9107
TEST_TIME 89 9039
TEST_TIME 90 9032
TEST_TIME 91 8998
TEST_TIME 92 8965
TEST_TIME 93 8964
TEST_TIME 94 8928
TEST_TIME 95 8975
TEST_TIME 96 8945
TEST_TIME 97 8956
TEST_TIME 98 8994
TEST_TIME 99 8945
TEST_TIME 100 8960
TEST_TIME 101 8928
TEST_TIME 102 8963
TEST_TIME 103 8957
TEST_TIME 104 8939
TEST_TIME 105 8935
TEST_TIME 106 8973
TEST_TIME 107 8937
TEST_TIME 108 8868
TEST_TIME 109 8867
TEST_TIME 110 8845
TEST_TIME 111 8820
TEST_TIME 112 8772
TEST_TIME 113 8813
TEST_TIME 114 8789
TEST_TIME 115 8798
TEST_TIME 116 8783
TEST_TIME 117 8764
TEST_TIME 118 8754
TEST_TIME 119 8785
TEST_TIME 120 8732

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2022 10:38:44 to 14-10-2022 10:38:48.

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 1444826 1444826 0 0.00 0 26204 15 0 0
doc_list_biud 1444807 1444807 0 0.00 0 35656 48 0 0
sp_add_invoice_to_stock 4658 4571 87 1.86 36 241660 30026 0 87
sp_cancel_adding_invoice 5307 5307 0 0.00 6 140409 1312 0 0
sp_cancel_client_order 1575 1575 0 0.00 6 9311 436 0 0
sp_cancel_customer_reserve 533289 533289 0 0.00 1 23398 113 0 0
sp_cancel_pay_from_customer 594 594 0 0.00 4 9753 168 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 3 2450 130 0 0
sp_cancel_supplier_invoice 5975 5968 7 0.11 2 35707 1137 0 7
sp_cancel_supplier_order 416 416 0 0.00 15 1394751 186162 0 0
sp_cancel_write_off 8391 8391 0 0.00 2 23168 180 0 0
sp_client_order 19007 19007 0 0.00 6 35103 529 0 0
sp_customer_reserve 432668 432668 0 0.00 1 98061 283 0 0
sp_fill_shopping_cart 29073 29073 0 0.00 1 17224 81 0 0
sp_fill_shopping_cart_clo_res 432640 432640 0 0.00 0 15938 21 0 0
sp_get_clo_for_invoice 4529 4529 0 0.00 0 22092 321 0 0
sp_kill_cost_storno 14664 14664 0 0.00 0 7569 84 0 0
sp_kill_qstorno_ret_qs2qd 1081353 1081353 0 0.00 0 35653 49 0 0
sp_kill_qty_storno 568610 568610 0 0.00 0 35656 112 0 0
sp_lock_selected_doc 18896 18896 0 0.00 0 8858 9 0 0
sp_make_cost_storno 25480 25480 0 0.00 0 23303 93 0 0
sp_multiply_rows_for_pdistr 25480 25480 0 0.00 0 9653 42 0 0
sp_multiply_rows_for_qdistr 19007 19007 0 0.00 0 13620 214 0 0
sp_pay_from_customer 6303 6303 0 0.00 5 14391 183 0 0
sp_pay_to_supplier 5511 5511 0 0.00 7 14887 246 0 0
sp_payment_common 11814 11814 0 0.00 2 10026 53 0 0
sp_qd_handle_on_cancel_clo 1575 1575 0 0.00 0 9302 360 0 0
sp_qd_handle_on_invoice_upd_sts 9834 9834 0 0.00 0 27347 589 0 0
sp_qd_handle_on_reserve_upd_sts 9137 9137 0 0.00 0 19085 63 0 0
sp_reserve_write_off 9137 9137 0 0.00 9 28236 300 0 0
sp_supplier_invoice 5016 5016 0 0.00 8 70558 3249 0 0
sp_supplier_order 5057 5057 0 0.00 8 41324 1476 0 0
srv_aggregate_perf_data 92 92 0 0.00 2290 113630 16986 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 755 127962 32685 0 0
srv_find_qd_qs_mism 1000330 1000330 0 0.00 0 15525 11 0 0
srv_make_invnt_saldo 437 437 0 0.00 63 97747 4072 0 0
srv_make_money_saldo 855 855 0 0.00 1 12840 377 0 0
srv_recalc_idx_stat 4 4 0 0.00 69686 395551 226839 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 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 4576 4576 0 0.00 1 11897 977 0 0
t$perf-norm:sp_cancel_adding_invoice 348 348 0 0.00 2 8021 448 0 0
t$perf-norm:sp_cancel_client_order 1575 1575 0 0.00 0 5799 31 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 4252 47 0 0
t$perf-norm:sp_cancel_pay_from_customer 594 594 0 0.00 0 901 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 2010 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 4111 61 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 85277 7643 0 0
t$perf-norm:sp_cancel_write_off 890 890 0 0.00 0 6578 32 0 0
t$perf-norm:sp_client_order 19007 19007 0 0.00 0 7048 41 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 485 13 0 0
t$perf-norm:sp_pay_from_customer 6303 6303 0 0.00 0 7095 21 0 0
t$perf-norm:sp_pay_to_supplier 5511 5511 0 0.00 0 14732 25 0 0
t$perf-norm:sp_reserve_write_off 9137 9137 0 0.00 0 7081 23 0 0
t$perf-norm:sp_supplier_invoice 5018 5018 0 0.00 0 5750 33 0 0
t$perf-norm:sp_supplier_order 5057 5057 0 0.00 0 3891 31 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 982 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 128 5 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 1138 27 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 642 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 216 76 0 0
v_add_invoice_to_stock 9224 9050 174 1.88 0 10597 29 0 174
v_all_customers 34684 34684 0 0.00 0 197 4 0 0
v_all_suppliers 28714 28714 0 0.00 0 66 4 0 0
v_all_wares 38014 38014 0 0.00 0 153 4 0 0
v_cancel_adding_invoice 696 696 0 0.00 0 1150 15 0 0
v_cancel_client_order 3150 3150 0 0.00 0 3509 11 0 0
v_cancel_customer_prepayment 1188 1188 0 0.00 0 7615 28 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 1 354 9 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 2402 15 0 0
v_cancel_supplier_invoice 780 766 14 1.79 0 1793 26 0 14
v_cancel_supplier_order 832 832 0 0.00 0 2834 17 0 0
v_cancel_write_off 1780 1780 0 0.00 0 973 23 0 0
v_max_id_clo_ord 5055 5055 0 0.00 0 3500 5 0 0
v_max_id_clo_res 927 927 0 0.00 0 2214 16 0 0
v_max_id_ord_sup 5011 5011 0 0.00 0 3372 7 0 0
v_max_non_paid_invoice 1220 1220 0 0.00 0 10020 17 0 0
v_max_non_paid_realizn 3223 3223 0 0.00 0 609 7 0 0
v_min_id_clo_ord 5055 5055 0 0.00 0 3101 8 0 0
v_min_id_clo_res 927 927 0 0.00 0 3663 20 0 0
v_min_id_ord_sup 5011 5011 0 0.00 0 4191 8 0 0
v_min_non_paid_invoice 5511 5511 0 0.00 0 6202 25 0 0
v_min_non_paid_realizn 6303 6303 0 0.00 0 9465 24 0 0
v_our_firm 9490 9490 0 0.00 0 138 5 0 0
v_reserve_write_off 18274 18274 0 0.00 0 10669 13 0 0
x_lock_depdocs_on_canc_invoice 10892 10892 0 0.00 0 27046 147 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 6298 367 0 0
x_make_qstorno_1000_1200 5057 5057 0 0.00 3 41281 1314 0 0
x_make_qstorno_1200_2000 5016 5016 0 0.00 4 70472 3020 0 0
x_make_qstorno_2100_3300 432666 432666 0 0.00 0 98058 249 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2022 10:38:49 to 14-10-2022 10:38:53.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:26 41251 8192000000 9723106784 10018914304 798698816 8.883 100 93 186 0 829162336 7775296 22688224
08:10:13 14913 8192000000 9748002160 10051866624 821409312 9.113 100 91 182 0 856906432 13613056 21884064
08:11:46 9502 8192000000 9793288160 10110726144 858066272 9.481 100 85 169 0 904477792 27204096 19207424
08:13:06 4187 8192000000 9873495728 10209566720 932148992 10.216 100 80 156 0 994727168 45947296 16630880
08:14:21 3939 8192000000 9955959152 10301100032 971989744 10.606 100 92 182 0 1064409728 73286992 19132992
08:15:30 1358 8192000000 9980678336 10336419840 1003356800 10.911 100 87 164 0 1101024160 81112144 16555216
08:16:34 1670 8192000000 10007598080 10374774784 1025582704 11.126 100 96 185 2 1115968256 72217232 18168320
08:17:37 990 8192000000 10013782432 10386370560 1044712720 11.310 100 90 173 2 1137859488 75271536 17875232
08:18:40 1292 8192000000 10044097456 10420154368 1062294816 11.478 100 93 180 1 1165467392 84831120 18341456
08:19:42 3153 8192000000 10042576608 10419879936 1070708608 11.559 100 85 165 2 1164691760 77445648 16537504
08:20:46 1191 8192000000 10045841824 10427785216 1082993568 11.676 100 84 166 2 1165537840 66618768 15925504
08:21:50 1158 8192000000 10068147504 10457219072 1083866896 11.684 100 96 182 0 1187907168 85334928 18705344
08:22:53 1394 8192000000 10052357360 10443386880 1089928112 11.742 100 83 160 5 1181787968 74843472 17016384
08:23:56 1119 8192000000 10065641280 10452152320 1094408896 11.785 100 91 176 1 1188196656 75579056 18208704
08:24:59 998 8192000000 10074298304 10466607104 1099318816 11.831 100 91 182 1 1198413824 81023264 18071744
08:26:01 1056 8192000000 10074877696 10464178176 1102095200 11.858 100 91 177 0 1194720544 75747664 16877680
08:27:03 2514 8192000000 10060839904 10453291008 1101418224 11.851 100 77 148 0 1182949216 66857328 14673664
08:28:08 1014 8192000000 10055165984 10442735616 1097624032 11.815 100 94 166 6 1175668880 62899664 15145184
08:29:10 1148 8192000000 10042098848 10424897536 1085760304 11.702 100 97 188 2 1162864528 58116416 18987808
08:30:12 6673 8192000000 10043388448 10435289088 1075842912 11.608 99 57 112 1 1168174720 80917120 11414688
08:31:20 877 8192000000 10017165328 10401185792 1075479856 11.604 100 89 175 2 1141786656 48524496 17782304
08:32:21 1287 8192000000 10009476544 10389733376 1059693600 11.454 100 82 156 2 1128880224 54669888 14516736
08:33:24 1619 8192000000 9981954176 10359406592 1040366400 11.268 99 82 147 6 1107498512 52337024 14795088
08:34:26 1082 8192000000 10011525440 10385506304 1032937888 11.197 99 92 181 2 1135274672 83540704 18796080
08:35:28 930 8192000000 9967327072 10336665600 1035301088 11.219 100 84 162 1 1089833008 38837712 15694208
08:36:29 1307 8192000000 9952398048 10332102656 1035102048 11.218 100 73 137 1 1077406768 28657888 13646832
08:37:32 10717 8192000000 10010086112 10391703552 1061855456 11.474 100 71 137 0 1129541312 53927680 13758176
08:38:52 1672 8192000000 10022059120 10403627008 1063324704 11.488 100 81 143 1 1146000688 68403568 14272416
08:40:02 2486 8192000000 10023432560 10413363200 1075259040 11.602 99 64 115 3 1143630816 54586240 13785536
08:41:09 7619 8192000000 10046673184 10439282688 1095964544 11.799 100 78 140 1 1168420896 58249584 14206768
08:42:26 20248 8192000000 10091613344 10483073024 1108682544 11.920 100 72 132 1 1201049216 78181920 14184752
08:43:54 3531 8192000000 10147578304 10548494336 1124735456 12.072 100 86 165 1 1260111136 117565616 17810064
08:45:00 1109 8192000000 10151651872 10546700288 1131878816 12.139 100 88 167 1 1266545664 116549568 18117280
08:46:06 1500 8192000000 10164588704 10565349376 1143854880 12.252 100 90 173 3 1284657184 121869648 18932656
08:47:18 5882 8192000000 10184579392 10597916672 1151550176 12.324 100 68 130 1 1298950048 132548848 14851024
08:48:30 869 8192000000 10259668752 10655256576 1168410688 12.482 100 98 187 2 1375365328 185105648 21848992
08:49:33 855 8192000000 10271715504 10672181248 1177195840 12.564 100 99 193 0 1383918992 186049344 20673808
08:50:39 1765 8192000000 10304884416 10709774336 1185748576 12.644 100 79 153 2 1415620144 210657408 19214160
08:51:43 2447 8192000000 10335553520 10752040960 1196888192 12.747 100 88 169 0 1451025616 234685520 19451904
08:52:50 1002 8192000000 10402037568 10804371456 1205330928 12.826 100 95 181 2 1508596048 281448176 21816944
08:53:58 812 8192000000 10446976448 10851954688 1214583712 12.912 100 98 194 1 1558092224 321220304 22288208
08:55:04 1820 8192000000 10509567328 10923077632 1219982912 12.962 100 97 187 0 1613487648 370759904 22744832
08:56:11 1269 8192000000 10511434352 10932572160 1203452608 12.808 99 96 189 2 1617935456 391639648 22843200
08:57:18 1342 8192000000 10578852256 10999107584 1219238304 12.955 100 98 196 1 1686009888 442386032 24385552
08:58:22 1073 8192000000 10618445056 11044777984 1212344896 12.891 100 96 187 1 1725263424 490040480 22878048
08:59:28 871 8192000000 10655736016 11082244096 1195114432 12.731 99 96 191 0 1759828512 540833744 23880336
09:00:31 903 8192000000 10662099968 11137449984 1196096608 12.740 100 96 187 0 1767768672 548747616 22924448
09:01:37 1103 8192000000 10618020560 11077656576 1209551792 12.865 100 95 188 3 1721909168 488418672 23938704
09:02:44 739 8192000000 10587862320 11022331904 1201828272 12.793 100 99 192 0 1689554448 463230096 24496080
09:03:47 2244 8192000000 10536432064 11033284608 1191418704 12.697 100 73 146 0 1639834336 429848944 18566688
09:04:51 10057 8192000000 10440538800 10906820608 1192486064 12.706 100 56 101 2 1541653920 337292960 11874896
09:06:16 23728 8192000000 10522448720 10978791424 1185975824 12.646 99 35 70 0 1614513248 419361280 9176144
09:08:03 35153 8192000000 10520165392 11031527424 1178350208 12.575 100 26 52 0 1615209760 430001056 6858496
09:09:44 22935 8192000000 10375425136 10874011648 1159101184 12.395 100 29 57 1 1521238064 354677744 7459136
09:11:15 33286 8192000000 10174562800 10729246720 1144801104 12.261 99 19 38 0 1285983552 136403120 4779328
09:12:50 27266 8192000000 10139855264 10578407424 1119661776 12.024 97 27 50 0 1218008976 93269616 5077584
09:14:19 48435 8192000000 10129398304 10570633216 1142288768 12.237 100 20 40 0 1236703824 90110192 4304864
09:16:09 95622 8192000000 10061203888 10496356352 1099980048 11.837 97 41 80 0 1134989744 26850448 8159248
09:18:46 62595 8192000000 10090608048 10536263680 1120938352 12.036 99 35 66 0 1178999632 50955568 7105712
09:20:54 24277 8192000000 10117432672 10571927552 1129938784 12.121 99 35 67 1 1209920016 72197216 7784016
09:22:43 30506 8192000000 10133529184 10603356160 1126970864 12.093 99 40 73 0 1236774432 100926880 8876688
09:24:16 7879 8192000000 10123815120 10562891776 1124688064 12.071 99 40 76 2 1223935472 88551936 10695472
09:25:50 94396 8192000000 10186084176 10636873728 1127152096 12.095 99 23 46 0 1250312400 117470880 5689424
09:29:08 67630 8192000000 10111081264 10616991744 1117200112 12.001 99 12 24 0 1222775392 102504832 3070448
09:31:17 59883 8192000000 10055085648 10503405568 1085353328 11.698 97 23 43 0 1139237152 49146608 4737216
09:33:18 14742 8192000000 10139535280 10565562368 1125996112 12.084 100 63 122 0 1224452928 85362144 13094672
09:34:36 40465 8192000000 10060763680 10523299840 1096981888 11.809 98 24 46 1 1152278224 50528448 4767888
09:36:18 15143 8192000000 10091328000 10518061056 1116856528 11.997 100 48 92 0 1183036896 57096800 9083568
09:37:35 43098 8192000000 10100851952 10534166528 1112299280 11.954 99 27 52 0 1189372912 72104464 4969168
09:39:19 26458 8192000000 10118513040 10565414912 1113511504 11.966 99 38 76 0 1192876176 71903616 7461056
09:40:50 90615 8192000000 10089215344 10538676224 1122120816 12.047 100 43 86 1 1208040784 77150208 8769760
09:43:32 45805 8192000000 10089103760 10555342848 1103180736 11.868 98 32 64 0 1166416496 56259296 6976464
09:45:36 52360 8192000000 10044844928 10514776064 1108190048 11.915 99 8 14 0 1159411472 49269104 1952320
09:47:36 62639 8192000000 10073327136 10548129792 1114286304 11.973 99 11 22 0 1166836000 50257728 2291968
09:49:38 46958 8192000000 10099888128 10560741376 1097508848 11.814 97 25 50 0 1150233712 46704368 6020496
09:51:26 46918 8192000000 10090769264 10543439872 1094652000 11.787 97 21 42 0 1170833808 71606736 4575072
09:53:22 14916 8192000000 10122759152 10558689280 1129667488 12.118 100 33 66 0 1218997536 82163712 7166336
09:54:45 72155 8192000000 10130357856 10568982528 1115298400 11.983 98 24 48 0 1201749984 81227168 5224416
09:56:58 33295 8192000000 10165696192 10597986304 1124550464 12.070 99 31 62 0 1247220288 115741648 6928176
09:58:33 13799 8192000000 10209684080 10637668352 1117391504 12.002 98 40 80 0 1285787904 160008736 8387664
09:59:51 30186 8192000000 10146657664 10606821376 1099802016 11.836 97 17 32 0 1230819104 127452384 3564704
10:01:21 51622 8192000000 10102233008 10528841728 1103475328 11.871 98 37 74 0 1170399536 58517376 8406832
10:03:14 23694 8192000000 10068012384 10504445952 1114338576 11.973 99 50 96 2 1170796960 46065616 10392768
10:04:50 77029 8192000000 10088107552 10549276672 1120989152 12.036 99 20 38 0 1192869904 68129456 3751296
10:07:07 47949 8192000000 10059748736 10560655360 1120251584 12.029 100 8 15 1 1164120640 42880224 988832
10:09:03 58678 8192000000 10095867360 10560053248 1136575968 12.183 100 22 43 0 1177990272 36802576 4611728
10:11:09 52657 8192000000 10087555648 10528677888 1108776240 11.921 97 19 38 0 1169603632 56596512 4230880
10:13:26 18792 8192000000 10073818112 10505818112 1125703104 12.081 100 42 80 0 1168983104 35260528 8019472
10:14:47 61247 8192000000 10068111408 10529583104 1113915760 11.969 98 13 23 0 1154187152 38302336 1969056
10:17:00 67360 8192000000 10085297936 10546794496 1116287264 11.992 98 34 66 2 1177796384 54823504 6685616
10:19:16 52189 8192000000 10072671776 10522873856 1075967072 11.609 95 36 70 1 1124475600 41805360 6703168
10:21:11 23332 8192000000 10075953664 10499190784 1120017248 12.027 99 36 70 1 1161646928 35452528 6177152
10:22:40 25811 8192000000 10139868224 10588717056 1125306240 12.077 100 49 98 2 1218145568 83210544 9628784
10:24:06 127962 8192000000 10097011584 10545053696 1134545936 12.164 100 22 42 0 1204915088 66290672 4078480
10:27:32 87515 8192000000 10132860480 10614730752 1124224400 12.067 99 20 40 0 1208621344 79678288 4718656
10:30:00 61870 8192000000 10101992240 10594365440 1115640912 11.986 98 27 54 0 1217266800 95566896 6058992
10:32:05 87932 8192000000 10043581536 10502656000 1083632688 11.682 97 13 26 0 1138900272 52868656 2398928
10:34:40 60120 8192000000 10080250960 10524491776 1086789408 11.712 97 28 54 0 1156645040 63252032 6603600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-10-2022 10:38:55 to 14-10-2022 10:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 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 14-10-2022 10:39:12 to 14-10-2022 10:39:14.

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          215914
MON$OLDEST_ACTIVE               215915
MON$OLDEST_SNAPSHOT             215915
MON$NEXT_TRANSACTION            215915
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                       2033056
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

14.10.22 10:39:15. 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
2022-10-14 10:39:17 No severe PSQL-related problems occured
14.10.22 10:39:19. End of report.