Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.2023 19:39
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 15023484 kB
MemAvailable 30379472 kB
Buffers 0 kB
Cached 16659872 kB
SwapCached 4460 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2023-01-13 19:38:39
DTS_END                         2023-01-13 19:38:39.2400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59502
TRN_ID                          243459
ATT_ID                          664
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: 9884.06 20350 1186087
customer order: creation 170.30 335 20436
customer order: refuse 14.40 382 1728
order to supplier: creation 44.85 1193 5382
order to supplier: removal 3.63 100106 436
invoice (draft): creation 44.66 3137 5359
invoice (draft): removal 57.49 673 6899
invoice accept: apply 42.28 17401 5073
invoice accept: cancel 52.22 743 6266
customer reserve: creation 4010.35 168 481242
customer reserve: removal 5133.83 56 616060
realization accept: apply 84.28 203 10113
realization accept: cancel 97.42 103 11690
payment from customer: creation 59.14 133 7097
payment from customer: removal 5.68 92 682
payment to supplier: creation 48.19 162 5783
payment to supplier: removal 3.50 50 420
service: total inventory turnovers 4.66 1090 559
service: total monetary turnovers 6.25 56 750
service: aggregate perf. data 0.90 21270 108
service: refresh index statistics 0.03 259644 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-01-2023 19:39:30 to 13-01-2023 19:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4440
TEST_TIME 2 2438
TEST_TIME 3 4699
TEST_TIME 4 4397
TEST_TIME 5 4737
TEST_TIME 6 4904
TEST_TIME 7 5142
TEST_TIME 8 5073
TEST_TIME 9 5444
TEST_TIME 10 5696
TEST_TIME 11 5728
TEST_TIME 12 6318
TEST_TIME 13 6322
TEST_TIME 14 6549
TEST_TIME 15 7032
TEST_TIME 16 7331
TEST_TIME 17 7708
TEST_TIME 18 7923
TEST_TIME 19 8150
TEST_TIME 20 8236
TEST_TIME 21 8435
TEST_TIME 22 8428
TEST_TIME 23 8767
TEST_TIME 24 9029
TEST_TIME 25 9278
TEST_TIME 26 9225
TEST_TIME 27 9373
TEST_TIME 28 9469
TEST_TIME 29 9934
TEST_TIME 30 9839
TEST_TIME 31 11090
TEST_TIME 32 11153
TEST_TIME 33 10906
TEST_TIME 34 11075
TEST_TIME 35 11040
TEST_TIME 36 10987
TEST_TIME 37 10792
TEST_TIME 38 10687
TEST_TIME 39 10438
TEST_TIME 40 10406
TEST_TIME 41 10478
TEST_TIME 42 10444
TEST_TIME 43 10322
TEST_TIME 44 10189
TEST_TIME 45 10163
TEST_TIME 46 10040
TEST_TIME 47 10026
TEST_TIME 48 10153
TEST_TIME 49 10162
TEST_TIME 50 10105
TEST_TIME 51 10146
TEST_TIME 52 10043
TEST_TIME 53 9934
TEST_TIME 54 9995
TEST_TIME 55 10045
TEST_TIME 56 10068
TEST_TIME 57 10053
TEST_TIME 58 10063
TEST_TIME 59 9985
TEST_TIME 60 9950
TEST_TIME 61 9902
TEST_TIME 62 10113
TEST_TIME 63 10105
TEST_TIME 64 10150
TEST_TIME 65 10155
TEST_TIME 66 10191
TEST_TIME 67 10245
TEST_TIME 68 10293
TEST_TIME 69 10274
TEST_TIME 70 10234
TEST_TIME 71 10235
TEST_TIME 72 10249
TEST_TIME 73 10121
TEST_TIME 74 10081
TEST_TIME 75 10008
TEST_TIME 76 9973
TEST_TIME 77 9949
TEST_TIME 78 9937
TEST_TIME 79 9871
TEST_TIME 80 9892
TEST_TIME 81 9940
TEST_TIME 82 9941
TEST_TIME 83 9863
TEST_TIME 84 9852
TEST_TIME 85 9812
TEST_TIME 86 9832
TEST_TIME 87 9784
TEST_TIME 88 9813
TEST_TIME 89 9820
TEST_TIME 90 9823
TEST_TIME 91 9842
TEST_TIME 92 9908
TEST_TIME 93 9903
TEST_TIME 94 9986
TEST_TIME 95 10006
TEST_TIME 96 10012
TEST_TIME 97 10027
TEST_TIME 98 10096
TEST_TIME 99 10055
TEST_TIME 100 10077
TEST_TIME 101 10031
TEST_TIME 102 9979
TEST_TIME 103 9998
TEST_TIME 104 9946
TEST_TIME 105 9985
TEST_TIME 106 9945
TEST_TIME 107 9921
TEST_TIME 108 9883
TEST_TIME 109 9845
TEST_TIME 110 9838
TEST_TIME 111 9780
TEST_TIME 112 9843
TEST_TIME 113 9854
TEST_TIME 114 9840
TEST_TIME 115 9804
TEST_TIME 116 9792
TEST_TIME 117 9894
TEST_TIME 118 9907
TEST_TIME 119 9918
TEST_TIME 120 9890

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2023 19:39:34 to 13-01-2023 19:39:37.

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 1629776 1629776 0 0.00 0 28831 6 0 0
doc_list_biud 1629776 1629776 0 0.00 0 36822 27 0 0
sp_add_invoice_to_stock 5169 5073 96 1.85 36 155506 17402 0 96
sp_cancel_adding_invoice 6266 6266 0 0.00 4 66819 744 0 0
sp_cancel_client_order 1728 1728 0 0.00 4 15755 383 0 0
sp_cancel_customer_reserve 616060 616060 0 0.00 0 36826 57 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 5877 93 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 1152 50 0 0
sp_cancel_supplier_invoice 6902 6899 3 0.04 1 35184 673 0 3
sp_cancel_supplier_order 436 436 0 0.00 6 921156 100107 0 0
sp_cancel_write_off 11690 11690 0 0.00 1 28854 104 0 0
sp_client_order 20436 20436 0 0.00 3 17586 335 0 0
sp_customer_reserve 481242 481242 0 0.00 0 45728 168 0 0
sp_fill_shopping_cart 31177 31177 0 0.00 1 9935 36 0 0
sp_fill_shopping_cart_clo_res 481240 481240 0 0.00 0 19813 13 0 0
sp_get_clo_for_invoice 5067 5067 0 0.00 0 22242 287 0 0
sp_kill_cost_storno 19058 19058 0 0.00 0 16294 48 0 0
sp_kill_qstorno_ret_qs2qd 1251145 1251145 0 0.00 0 35168 30 0 0
sp_kill_qty_storno 658259 658259 0 0.00 0 36822 65 0 0
sp_lock_selected_doc 20908 20908 0 0.00 0 7308 5 0 0
sp_make_cost_storno 28060 28060 0 0.00 0 16480 66 0 0
sp_multiply_rows_for_pdistr 28060 28060 0 0.00 0 15841 31 0 0
sp_multiply_rows_for_qdistr 20436 20436 0 0.00 0 17580 149 0 0
sp_pay_from_customer 7097 7097 0 0.00 4 16159 134 0 0
sp_pay_to_supplier 5783 5783 0 0.00 5 15908 163 0 0
sp_payment_common 12880 12880 0 0.00 1 12185 40 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 0 15748 357 0 0
sp_qd_handle_on_invoice_upd_sts 11333 11333 0 0.00 0 12336 314 0 0
sp_qd_handle_on_reserve_upd_sts 10113 10113 0 0.00 0 18302 66 0 0
sp_reserve_write_off 10113 10113 0 0.00 6 18429 204 0 0
sp_supplier_invoice 5359 5359 0 0.00 6 81628 3138 0 0
sp_supplier_order 5382 5382 0 0.00 6 29031 1193 0 0
srv_aggregate_perf_data 108 108 0 0.00 2658 363846 21270 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 961 153722 41886 0 0
srv_find_qd_qs_mism 1137027 1137027 0 0.00 0 28829 4 0 0
srv_make_invnt_saldo 559 559 0 0.00 40 46923 1090 0 0
srv_make_money_saldo 750 750 0 0.00 1 16310 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 107809 350316 259645 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5074 5074 0 0.00 0 13294 191 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 5141 93 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 3338 20 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 2732 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 675 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2088 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 601 13 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 22839 1651 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 2190 10 0 0
t$perf-norm:sp_client_order 20436 20436 0 0.00 0 8107 17 0 0
t$perf-norm:sp_customer_reserve 1086 1086 0 0.00 0 1932 7 0 0
t$perf-norm:sp_pay_from_customer 7097 7097 0 0.00 0 4828 12 0 0
t$perf-norm:sp_pay_to_supplier 5783 5783 0 0.00 0 8413 11 0 0
t$perf-norm:sp_reserve_write_off 10113 10113 0 0.00 0 10222 13 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 5459 19 0 0
t$perf-norm:sp_supplier_order 5382 5382 0 0.00 0 4402 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1481 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1012 18 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 1678 22 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 2179 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10324 10132 192 1.85 0 7280 17 0 192
v_all_customers 37906 37906 0 0.00 0 24 1 0 0
v_all_suppliers 30146 30146 0 0.00 0 133 1 0 0
v_all_wares 40872 40872 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 3236 10 0 0
v_cancel_client_order 3456 3456 0 0.00 0 9301 7 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 5656 13 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 2812 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 455 4 0 0
v_cancel_supplier_invoice 842 836 6 0.71 0 2883 21 0 6
v_cancel_supplier_order 872 872 0 0.00 0 271 3 0 0
v_cancel_write_off 1984 1984 0 0.00 0 7314 21 0 0
v_max_id_clo_ord 5382 5382 0 0.00 0 297 1 0 0
v_max_id_clo_res 1086 1086 0 0.00 0 3717 16 0 0
v_max_id_ord_sup 5359 5359 0 0.00 0 5457 7 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 2548 7 0 0
v_max_non_paid_realizn 3544 3544 0 0.00 0 3802 6 0 0
v_min_id_clo_ord 5382 5382 0 0.00 0 3510 8 0 0
v_min_id_clo_res 1086 1086 0 0.00 0 10625 15 0 0
v_min_id_ord_sup 5359 5359 0 0.00 0 4850 6 0 0
v_min_non_paid_invoice 5783 5783 0 0.00 0 10509 29 0 0
v_min_non_paid_realizn 7097 7097 0 0.00 0 12182 34 0 0
v_our_firm 10072 10072 0 0.00 0 22 1 0 0
v_reserve_write_off 20226 20226 0 0.00 0 10716 10 0 0
x_lock_depdocs_on_canc_invoice 12747 12747 0 0.00 0 15021 109 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 6885 349 0 0
x_make_qstorno_1000_1200 5382 5382 0 0.00 1 29005 1112 0 0
x_make_qstorno_1200_2000 5359 5359 0 0.00 3 81587 3009 0 0
x_make_qstorno_2100_3300 481242 481242 0 0.00 0 45722 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-01-2023 19:39:38 to 13-01-2023 19:39:43.

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
17:08:43 6 8192000000 8802542960 8815153152 8001296 0.097 4 1 2 0 8103552 59472 42784
17:09:44 37489 8192000000 9902097440 10209779712 983017856 10.714 100 93 186 0 1013786320 6155312 24613152
17:11:31 14425 8192000000 9934838304 10254204928 1019631248 11.068 100 87 174 0 1050833712 8825920 22376544
17:12:57 10414 8192000000 9969894800 10301632512 1056296912 11.421 100 82 164 0 1086873776 9638112 20938752
17:14:11 9920 8192000000 10044451072 10389409792 1122609344 12.052 100 81 160 1 1152759440 10860304 19289792
17:15:25 5318 8192000000 10102193968 10464727040 1188871632 12.673 100 79 158 0 1219651552 12862592 17917328
17:16:32 2007 8192000000 10143606848 10515738624 1228503120 13.040 100 82 160 0 1264111472 17928640 17679712
17:17:36 1979 8192000000 10161047856 10539892736 1249153120 13.230 100 83 165 0 1280345056 13570592 17621344
17:18:40 1177 8192000000 10201500128 10582634496 1283871504 13.548 100 88 176 1 1317721664 14162128 19688032
17:19:42 1597 8192000000 10206826896 10597056512 1296731536 13.666 100 77 153 1 1326069120 12688112 16649472
17:20:46 12748 8192000000 10205151712 10604675072 1307586704 13.764 100 46 89 1 1325092912 7273376 10232832
17:22:01 3369 8192000000 10199899344 10610135040 1312086496 13.805 100 35 70 0 1326589328 6717392 7785440
17:23:05 11218 8192000000 10209771696 10625871872 1321024256 13.886 100 20 40 0 1331090336 5500288 4565792
17:24:17 7969 8192000000 10209255200 10627825664 1325203760 13.924 100 34 69 2 1339451648 6739040 7508848
17:25:25 21259 8192000000 10236180608 10639564800 1326219680 13.933 100 61 122 0 1346585856 6670464 13695712
17:26:48 8837 8192000000 10225399488 10642038784 1331229296 13.978 100 22 44 1 1341274720 5100496 4944928
17:27:57 9773 8192000000 10207706928 10622910464 1299034752 13.686 98 16 32 0 1308114960 5132352 3947856
17:29:07 62671 8192000000 10144455936 10550730752 1131986688 12.140 87 24 43 0 1141597392 4575872 5034832
17:31:10 6724 8192000000 10133343216 10535530496 1211659584 12.884 96 17 31 0 1220423856 5061200 3703072
17:32:18 3980 8192000000 10142303232 10538868736 1246123184 13.203 100 31 62 0 1259232272 6156224 6952864
17:33:24 10620 8192000000 10174426048 10583678976 1281640128 13.528 100 20 41 0 1291950432 5230128 5080176
17:34:44 28012 8192000000 10182207680 10595287040 1281340240 13.525 99 20 40 0 1291896288 6114352 4441696
17:36:17 4772 8192000000 10197369408 10607579136 1301733968 13.711 100 31 63 0 1315642576 6567904 7340704
17:37:23 9422 8192000000 10204589472 10616012800 1307464672 13.763 100 18 37 1 1317196656 5755984 3976000
17:38:33 9760 8192000000 10226803552 10639548416 1330042160 13.968 100 24 48 0 1340065488 4417808 5605520
17:39:52 68892 8192000000 10271960896 10684137472 1351931216 14.165 100 47 95 0 1374490192 11202192 11356784
17:42:16 8164 8192000000 10278059664 10698039296 1366411312 14.295 100 50 97 2 1390782016 12849968 11520736
17:43:30 14588 8192000000 10282069632 10707763200 1377581568 14.395 100 39 78 0 1400957920 14288848 9087504
17:44:50 11704 8192000000 10296642960 10722680832 1384286336 14.455 100 44 88 0 1411394368 17041616 10066416
17:46:10 3338 8192000000 10307323104 10724175872 1385061776 14.462 100 53 102 1 1411223712 15201264 10960672
17:47:17 18339 8192000000 10325508576 10747904000 1390617600 14.511 99 53 106 1 1426671648 23333856 12720192
17:48:42 10048 8192000000 10340372896 10759872512 1398293024 14.580 99 66 128 0 1435556512 22072720 15190768
17:50:11 1516 8192000000 10321003632 10728820736 1370254448 14.329 99 71 142 2 1418015632 30457136 17304048
17:51:16 6805 8192000000 10332142928 10742525952 1393231024 14.535 100 74 145 0 1436022512 25581264 17210224
17:52:38 4330 8192000000 10312319392 10728755200 1372102176 14.346 99 53 106 1 1413564288 28346848 13115264
17:53:46 2186 8192000000 10340272048 10753581056 1392855232 14.531 100 70 139 0 1446985888 35445648 18685008
17:54:58 2407 8192000000 10315431936 10724364288 1361397616 14.250 99 81 163 2 1418285440 37290032 19597792
17:56:04 6073 8192000000 10317555056 10729164800 1367036816 14.300 100 62 118 1 1420856208 39052320 14767072
17:57:14 961 8192000000 10301476944 10704850944 1346290816 14.114 99 69 137 0 1403593472 40117744 17184912
17:58:21 10504 8192000000 10314729440 10731937792 1363457920 14.268 99 33 65 1 1413868944 42216112 8194912
17:59:36 5656 8192000000 10348686976 10763784192 1392450880 14.528 100 54 106 1 1453991824 47809712 13731232
18:00:45 6742 8192000000 10340439952 10755809280 1379582992 14.413 100 50 98 0 1446238784 53415552 13240240
18:01:56 10753 8192000000 10331484944 10749837312 1376750544 14.387 100 43 86 0 1438436832 49569536 12116752
18:03:13 18222 8192000000 10335028688 10756108288 1377317712 14.393 100 48 91 1 1443277056 53561520 12397824
18:04:34 8002 8192000000 10351240608 10778877952 1386869824 14.478 100 37 75 0 1450564688 53222256 10472608
18:06:04 21891 8192000000 10349306928 10786050048 1396336112 14.562 100 24 48 0 1456648896 53589344 6723440
18:07:38 30823 8192000000 10324903072 10780286976 1374763392 14.370 100 18 37 0 1438721056 58261648 5696016
18:09:11 76999 8192000000 10299289648 10760966144 1383118160 14.444 100 14 28 0 1423449904 37015088 3316656
18:11:28 32920 8192000000 10315091056 10752081920 1395807584 14.558 100 32 65 0 1414046160 10535632 7702944
18:13:02 77693 8192000000 10308768064 10747138048 1371100608 14.337 98 17 36 0 1385608176 9785984 4721584
18:15:27 28340 8192000000 10316765872 10750853120 1399073552 14.587 100 48 96 0 1421053728 10376960 11603216
18:16:59 80233 8192000000 10317037680 10757656576 1396876960 14.567 100 26 47 1 1410083408 7138144 6068304
18:19:23 91611 8192000000 10304318016 10746384384 1373407808 14.358 98 31 62 1 1394009760 13658416 6943536
18:22:00 42183 8192000000 10332875200 10777677824 1406505696 14.653 100 23 47 0 1424530480 12492368 5532416
18:23:44 55398 8192000000 10339813664 10791882752 1418210096 14.757 100 14 28 0 1441450896 20113136 3127664
18:25:42 26559 8192000000 10340509808 10797297664 1420044704 14.773 100 40 78 1 1449758224 20645600 9067920
18:27:17 21770 8192000000 10319261984 10790236160 1412478064 14.706 100 17 34 0 1431286704 15071568 3737072
18:28:40 37267 8192000000 10313368928 10775334912 1407988752 14.666 100 11 22 0 1426315712 15689824 2637136
18:30:23 63013 8192000000 10316488688 10775171072 1395204848 14.552 99 10 20 1 1411806272 13632912 2968512
18:32:27 63539 8192000000 10303274752 10773008384 1365346256 14.285 97 20 38 0 1392159968 21640528 5173184
18:34:38 49709 8192000000 10303571104 10780930048 1406301840 14.651 100 11 22 0 1421435840 12420480 2713520
18:36:58 44022 8192000000 10300304384 10762547200 1387311200 14.482 99 15 29 0 1402058240 11978688 2768352
18:38:45 22440 8192000000 10308735088 10758750208 1351780816 14.163 96 38 74 0 1372631952 12629488 8221648
18:40:29 44096 8192000000 10270958912 10721599488 1342468400 14.080 98 14 27 0 1356721040 11310864 2941776
18:42:17 73587 8192000000 10257896528 10717851648 1282660816 13.537 93 14 28 0 1297883040 11976448 3245776
18:44:56 153718 8192000000 10245363488 10701307904 1220676192 12.968 90 9 18 0 1242879392 20320368 1882832
18:48:31 9322 8192000000 10263255616 10691231744 1332674896 13.991 99 46 91 0 1351404432 8679024 10050512
18:49:40 82107 8192000000 10253700288 10715766784 1349963040 14.147 100 29 58 0 1370202112 13543360 6695712
18:52:47 47801 8192000000 10268432912 10710478848 1337586864 14.036 98 29 58 0 1352835168 8768016 6480288
18:54:41 11614 8192000000 10313814176 10746642432 1385446960 14.465 100 66 132 2 1414985248 14772224 14766064
18:55:54 81332 8192000000 10275457024 10735894528 1328654880 13.955 96 14 28 1 1352882784 20939504 3288400
18:58:28 34236 8192000000 10288700336 10746228736 1363638640 14.270 99 13 24 1 1387435296 20971056 2825600
19:00:05 61616 8192000000 10273534144 10733236224 1369813504 14.325 100 11 23 0 1380209776 8063904 2332368
19:02:07 39390 8192000000 10272959136 10732900352 1367579248 14.305 99 20 40 0 1383879808 11815136 4485424
19:03:50 56228 8192000000 10273147808 10741006336 1351030896 14.157 98 7 12 0 1363423648 11304128 1088624
19:05:59 55410 8192000000 10287993328 10752237568 1375585584 14.377 99 12 24 0 1389839472 11678400 2575488
19:07:58 48607 8192000000 10314006656 10769965056 1404976688 14.639 100 31 62 0 1424360880 12063344 7320848
19:09:57 74162 8192000000 10281740048 10736496640 1354187904 14.185 97 9 18 0 1366395584 10394192 1813488
19:12:18 43921 8192000000 10304667392 10772951040 1385356816 14.464 99 10 19 0 1410094864 22941264 1796784
19:14:02 34279 8192000000 10322372432 10773598208 1399576336 14.591 100 19 38 0 1412639456 9126048 3937072
19:16:17 118177 8192000000 10317121648 10760945664 1351694160 14.163 96 13 26 0 1368498032 13811520 2992352
19:19:17 107085 8192000000 10319813056 10784989184 1410097472 14.685 100 32 64 0 1431268304 13504176 7666656
19:22:13 36268 8192000000 10329857616 10790211584 1410356576 14.687 100 22 44 0 1433401280 17998176 5046528
19:23:50 55346 8192000000 10290535824 10760581120 1380320528 14.419 99 13 26 0 1396066368 12827808 2918032
19:25:46 44662 8192000000 10283537984 10740387840 1343054432 14.085 96 18 36 1 1357565632 10725680 3785520
19:27:31 86038 8192000000 10302738944 10775314432 1402012416 14.613 100 18 37 0 1420185968 14286608 3886944
19:29:59 38379 8192000000 10276942160 10751008768 1368319088 14.312 99 22 44 0 1392669904 19156608 5194208
19:31:42 47961 8192000000 10241569776 10696859648 1300845200 13.703 96 8 16 0 1309934400 7625168 1464032
19:33:45 54869 8192000000 10263743744 10712305664 1321616576 13.891 96 30 58 0 1342357264 13757536 6983152
19:35:49 128784 8192000000 10252905904 10713632768 1259566448 13.326 93 15 31 0 1274450960 11863696 3020816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-01-2023 19:39:45 to 13-01-2023 19:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-01-2023 19:40:01 to 13-01-2023 19:40:03.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242408
MON$OLDEST_ACTIVE               243667
MON$OLDEST_SNAPSHOT             243667
MON$NEXT_TRANSACTION            243667
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-03 16:41:55.3470
MON$PAGES                       2058912
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:147: 
:148: mscs-test-161.budzdorov.ru    Fri Jan 13 19:38:42 2023
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:150: 
--- end of diff output ---

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

13.01.23 19:40:04. There are 3 logs in /var/tmp/logs-oltp30 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
2023-01-13 19:40:07 No severe PSQL-related problems occured
13.01.23 19:40:08. End of report.