Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.05.2022 07: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 15973152 kB
MemAvailable 31337356 kB
Buffers 0 kB
Cached 15696840 kB
SwapCached 2440 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.2
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-05-30 07:37:18
DTS_END                         2022-05-30 07:37:18.6250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56782
TRN_ID                          217621
ATT_ID                          658
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: 9050.87 28961 1086104
customer order: creation 167.85 475 20142
customer order: refuse 14.51 458 1741
order to supplier: creation 46.34 1446 5561
order to supplier: removal 3.22 195366 386
invoice (draft): creation 45.80 3303 5496
invoice (draft): removal 50.34 1127 6041
invoice accept: apply 42.29 27248 5075
invoice accept: cancel 45.43 1397 5452
customer reserve: creation 3917.16 259 470059
customer reserve: removal 4419.88 111 530385
realization accept: apply 83.24 290 9989
realization accept: cancel 83.95 173 10074
payment from customer: creation 58.92 179 7070
payment from customer: removal 6.19 133 743
payment to supplier: creation 49.20 199 5904
payment to supplier: removal 3.82 136 458
service: total inventory turnovers 4.13 4247 496
service: total monetary turnovers 7.67 421 920
service: aggregate perf. data 0.90 18437 108
service: refresh index statistics 0.03 323813 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-05-2022 07:38:26 to 30-05-2022 07:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7661
TEST_TIME 2 6345
TEST_TIME 3 5809
TEST_TIME 4 5323
TEST_TIME 5 4873
TEST_TIME 6 5293
TEST_TIME 7 5535
TEST_TIME 8 5643
TEST_TIME 9 6116
TEST_TIME 10 6324
TEST_TIME 11 6469
TEST_TIME 12 6477
TEST_TIME 13 6507
TEST_TIME 14 6413
TEST_TIME 15 6516
TEST_TIME 16 6615
TEST_TIME 17 6757
TEST_TIME 18 6665
TEST_TIME 19 6699
TEST_TIME 20 6748
TEST_TIME 21 6795
TEST_TIME 22 6971
TEST_TIME 23 7077
TEST_TIME 24 7562
TEST_TIME 25 7602
TEST_TIME 26 7631
TEST_TIME 27 8131
TEST_TIME 28 7980
TEST_TIME 29 8169
TEST_TIME 30 8428
TEST_TIME 31 8765
TEST_TIME 32 9115
TEST_TIME 33 9281
TEST_TIME 34 9916
TEST_TIME 35 10214
TEST_TIME 36 10360
TEST_TIME 37 10337
TEST_TIME 38 10436
TEST_TIME 39 10627
TEST_TIME 40 10728
TEST_TIME 41 10597
TEST_TIME 42 10614
TEST_TIME 43 10544
TEST_TIME 44 10529
TEST_TIME 45 10479
TEST_TIME 46 10368
TEST_TIME 47 10295
TEST_TIME 48 10343
TEST_TIME 49 10143
TEST_TIME 50 9989
TEST_TIME 51 10004
TEST_TIME 52 10025
TEST_TIME 53 9898
TEST_TIME 54 9799
TEST_TIME 55 9766
TEST_TIME 56 9709
TEST_TIME 57 9648
TEST_TIME 58 9628
TEST_TIME 59 9610
TEST_TIME 60 9635
TEST_TIME 61 9533
TEST_TIME 62 9507
TEST_TIME 63 9379
TEST_TIME 64 9427
TEST_TIME 65 9502
TEST_TIME 66 9599
TEST_TIME 67 9612
TEST_TIME 68 9600
TEST_TIME 69 9608
TEST_TIME 70 9662
TEST_TIME 71 9667
TEST_TIME 72 9677
TEST_TIME 73 9710
TEST_TIME 74 9735
TEST_TIME 75 9669
TEST_TIME 76 9686
TEST_TIME 77 9680
TEST_TIME 78 9699
TEST_TIME 79 9629
TEST_TIME 80 9636
TEST_TIME 81 9596
TEST_TIME 82 9501
TEST_TIME 83 9474
TEST_TIME 84 9462
TEST_TIME 85 9449
TEST_TIME 86 9422
TEST_TIME 87 9393
TEST_TIME 88 9350
TEST_TIME 89 9292
TEST_TIME 90 9227
TEST_TIME 91 9216
TEST_TIME 92 9279
TEST_TIME 93 9267
TEST_TIME 94 9211
TEST_TIME 95 9234
TEST_TIME 96 9261
TEST_TIME 97 9218
TEST_TIME 98 9269
TEST_TIME 99 9332
TEST_TIME 100 9289
TEST_TIME 101 9338
TEST_TIME 102 9327
TEST_TIME 103 9319
TEST_TIME 104 9405
TEST_TIME 105 9388
TEST_TIME 106 9369
TEST_TIME 107 9337
TEST_TIME 108 9283
TEST_TIME 109 9245
TEST_TIME 110 9212
TEST_TIME 111 9158
TEST_TIME 112 9164
TEST_TIME 113 9141
TEST_TIME 114 9119
TEST_TIME 115 9111
TEST_TIME 116 9104
TEST_TIME 117 9093
TEST_TIME 118 9052
TEST_TIME 119 9062
TEST_TIME 120 9076

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-05-2022 07:38:30 to 30-05-2022 07:38:33.

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 1517560 1517560 0 0.00 0 18041 14 0 0
doc_list_biud 1517551 1517551 0 0.00 0 31618 45 0 0
sp_add_invoice_to_stock 5236 5075 161 3.07 49 220221 27249 0 161
sp_cancel_adding_invoice 5452 5452 0 0.00 7 78001 1398 0 0
sp_cancel_client_order 1741 1741 0 0.00 7 14867 459 0 0
sp_cancel_customer_reserve 530385 530385 0 0.00 1 31432 111 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 3 4834 134 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 4 5766 137 0 0
sp_cancel_supplier_invoice 6045 6041 4 0.06 3 17694 1128 0 4
sp_cancel_supplier_order 386 386 0 0.00 8 1488863 195367 0 0
sp_cancel_write_off 10074 10074 0 0.00 2 11170 174 0 0
sp_client_order 20142 20142 0 0.00 6 11996 475 0 0
sp_customer_reserve 470059 470059 0 0.00 1 109382 259 0 0
sp_fill_shopping_cart 31186 31186 0 0.00 1 14646 86 0 0
sp_fill_shopping_cart_clo_res 470019 470019 0 0.00 0 12633 18 0 0
sp_get_clo_for_invoice 5030 5030 0 0.00 0 29459 278 0 0
sp_kill_cost_storno 16727 16727 0 0.00 0 12797 80 0 0
sp_kill_qstorno_ret_qs2qd 1077271 1077271 0 0.00 0 31389 48 0 0
sp_kill_qty_storno 569094 569094 0 0.00 0 31617 111 0 0
sp_lock_selected_doc 21003 21003 0 0.00 0 10668 7 0 0
sp_make_cost_storno 27992 27992 0 0.00 0 14809 84 0 0
sp_multiply_rows_for_pdistr 27988 27988 0 0.00 0 11750 40 0 0
sp_multiply_rows_for_qdistr 20142 20142 0 0.00 0 11290 174 0 0
sp_pay_from_customer 7070 7070 0 0.00 5 10375 179 0 0
sp_pay_to_supplier 5904 5904 0 0.00 7 10538 199 0 0
sp_payment_common 12973 12973 0 0.00 2 9295 45 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 1 14811 380 0 0
sp_qd_handle_on_invoice_upd_sts 10481 10481 0 0.00 0 31616 565 0 0
sp_qd_handle_on_reserve_upd_sts 9985 9985 0 0.00 0 30323 70 0 0
sp_reserve_write_off 9989 9989 0 0.00 9 30373 290 0 0
sp_supplier_invoice 5496 5496 0 0.00 9 79473 3304 0 0
sp_supplier_order 5561 5561 0 0.00 8 18858 1447 0 0
srv_aggregate_perf_data 108 108 0 0.00 2804 236418 18438 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 879 128388 31858 0 0
srv_find_qd_qs_mism 1036256 1036256 0 0.00 0 18037 10 0 0
srv_make_invnt_saldo 496 496 0 0.00 61 91436 4247 0 0
srv_make_money_saldo 920 920 0 0.00 1 11962 421 0 0
srv_recalc_idx_stat 4 4 0 0.00 218491 447779 323814 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5075 5075 0 0.00 1 20170 1010 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 6143 496 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 24793 50 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 1729 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 3881 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 5035 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 3648 44 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 119203 9541 0 0
t$perf-norm:sp_cancel_write_off 1111 1111 0 0.00 0 4242 29 0 0
t$perf-norm:sp_client_order 20142 20142 0 0.00 0 9807 41 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 5611 28 0 0
t$perf-norm:sp_pay_from_customer 7070 7070 0 0.00 0 18781 29 0 0
t$perf-norm:sp_pay_to_supplier 5904 5904 0 0.00 0 6618 26 0 0
t$perf-norm:sp_reserve_write_off 9989 9989 0 0.00 0 8843 29 0 0
t$perf-norm:sp_supplier_invoice 5496 5496 0 0.00 0 9195 39 0 0
t$perf-norm:sp_supplier_order 5561 5561 0 0.00 0 4530 36 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 392 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 2164 38 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 2083 24 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 620 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 362 147 0 0
v_add_invoice_to_stock 10378 10056 322 3.10 0 6422 27 0 322
v_all_customers 36564 36564 0 0.00 0 193 4 0 0
v_all_suppliers 30868 30868 0 0.00 0 201 4 0 0
v_all_wares 40284 40284 0 0.00 0 60 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2925 19 0 0
v_cancel_client_order 3482 3482 0 0.00 0 1458 11 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 4806 17 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2803 14 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 1768 16 0 0
v_cancel_supplier_invoice 882 874 8 0.90 0 1090 23 0 8
v_cancel_supplier_order 772 772 0 0.00 0 1983 16 0 0
v_cancel_write_off 2222 2222 0 0.00 0 1089 22 0 0
v_max_id_clo_ord 5557 5557 0 0.00 0 85 5 0 0
v_max_id_clo_res 993 993 0 0.00 0 2816 15 0 0
v_max_id_ord_sup 5486 5486 0 0.00 0 5424 9 0 0
v_max_non_paid_invoice 1512 1512 0 0.00 0 5678 12 0 0
v_max_non_paid_realizn 3693 3693 0 0.00 0 5680 10 0 0
v_min_id_clo_ord 5557 5557 0 0.00 0 2243 9 0 0
v_min_id_clo_res 993 993 0 0.00 0 2549 14 0 0
v_min_id_ord_sup 5486 5486 0 0.00 0 4925 9 0 0
v_min_non_paid_invoice 5903 5903 0 0.00 0 6762 15 0 0
v_min_non_paid_realizn 7070 7070 0 0.00 0 4004 16 0 0
v_our_firm 10474 10474 0 0.00 0 51 5 0 0
v_reserve_write_off 19970 19970 0 0.00 0 4414 11 0 0
x_lock_depdocs_on_canc_invoice 11056 11056 0 0.00 0 14611 141 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 21502 460 0 0
x_make_qstorno_1000_1200 5561 5561 0 0.00 3 18840 1258 0 0
x_make_qstorno_1200_2000 5496 5496 0 0.00 5 78777 3080 0 0
x_make_qstorno_2100_3300 470058 470058 0 0.00 0 109372 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-05-2022 07:38:34 to 30-05-2022 07:38:38.

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
05:07:22 1133 8192000000 8938457072 8999895040 5635152 0.068 3 1 2 0 5736272 65344 35776
05:08:24 19977 8192000000 9716090912 10010050560 783473856 8.729 100 83 166 0 810970368 7126608 20369904
05:09:47 25172 8192000000 9759675296 10064375808 825293968 9.152 100 92 184 1 859284528 12195952 21794608
05:11:16 21846 8192000000 9805309728 10120863744 855301424 9.453 100 88 176 0 896788848 20963776 20523648
05:12:40 10906 8192000000 9851323296 10180894720 895673456 9.855 100 82 163 3 958600448 44919024 18007968
05:13:55 5330 8192000000 9895687216 10236424192 936604064 10.260 100 80 156 0 1000019952 47050624 16365264
05:15:03 2968 8192000000 9948454160 10296651776 970518192 10.592 100 86 171 1 1057328432 69289840 17520400
05:16:11 1960 8192000000 10009509424 10367004672 994460176 10.825 100 90 176 0 1111741952 99897216 17384560
05:17:16 1997 8192000000 9993346288 10354532352 1017713840 11.050 100 91 177 0 1098431392 62377152 18340400
05:18:20 1391 8192000000 10015402864 10385092608 1038485712 11.250 100 87 161 3 1125142832 71429232 15227888
05:19:25 1058 8192000000 10026533920 10398437376 1048041088 11.342 100 91 176 0 1134247520 68882016 17324416
05:20:29 897 8192000000 10049619600 10426224640 1058553184 11.443 100 89 163 0 1166465072 89810640 18101248
05:21:34 1116 8192000000 10045270560 10425978880 1065190144 11.506 100 92 176 1 1163111520 79994912 17926464
05:22:39 1388 8192000000 10033416496 10414211072 1065773456 11.512 100 92 171 1 1142248208 59824656 16650096
05:23:42 1331 8192000000 10064279488 10447208448 1067722352 11.530 100 94 179 0 1174341568 88955296 17663920
05:24:47 1283 8192000000 10068025552 10453196800 1075861632 11.608 100 94 185 1 1177234416 82561392 18811392
05:25:50 1233 8192000000 10061870704 10449870848 1080709216 11.654 100 95 183 1 1177175056 78191728 18274112
05:26:53 1325 8192000000 10062678288 10449219584 1084165952 11.687 100 98 187 0 1172229840 68638992 19424896
05:27:57 1244 8192000000 10058360080 10445307904 1077538016 11.624 99 90 172 0 1168589648 72910272 18141360
05:28:59 1295 8192000000 10079674976 10468585472 1094302800 11.784 100 90 169 1 1205787856 95143296 16341760
05:30:04 1265 8192000000 10059922704 10445389824 1090748560 11.750 100 97 181 2 1176696256 67270272 18677424
05:31:07 1058 8192000000 10056090208 10445856768 1089756032 11.740 100 94 179 1 1177624416 70031360 17837024
05:32:09 1310 8192000000 10034252880 10422644736 1076065088 11.610 100 82 150 0 1161551184 69052272 16433824
05:33:12 1007 8192000000 10039808656 10427072512 1066121776 11.515 100 76 143 2 1163308256 82373904 14812576
05:34:15 1255 8192000000 10017132064 10393980928 1056627488 11.424 99 97 182 1 1141492832 66807888 18057456
05:35:17 1336 8192000000 10038147328 10418745344 1061995488 11.476 100 91 178 0 1160278320 79519888 18762944
05:36:24 1004 8192000000 10026208912 10402619392 1048983680 11.351 100 93 178 0 1147176784 79422352 18770752
05:37:28 6447 8192000000 9990281072 10365300736 1027747168 11.147 100 74 146 0 1118321424 75257776 15316480
05:38:48 6540 8192000000 9984681056 10354524160 1025932416 11.129 99 70 134 0 1102918240 64362336 12623488
05:40:00 1408 8192000000 10019103552 10398150656 1062983568 11.485 100 92 179 1 1145244224 60692112 21568544
05:41:06 1516 8192000000 10065881840 10446614528 1073364336 11.584 100 89 172 0 1184302272 93050800 17887136
05:42:12 3540 8192000000 10097030832 10475225088 1074337168 11.593 99 81 150 3 1212032304 122613552 15081584
05:43:21 3056 8192000000 10132796464 10518388736 1099394272 11.832 100 78 148 1 1251599616 136709072 15496272
05:44:29 7883 8192000000 10169595024 10567176192 1091578496 11.758 99 83 164 1 1278583904 168111616 18893792
05:45:47 1973 8192000000 10170437376 10584133632 1118654112 12.014 100 90 175 2 1304166272 165985472 19526688
05:46:53 2164 8192000000 10177413312 10576334848 1132067808 12.141 100 92 174 0 1295046928 144546816 18432304
05:47:58 2477 8192000000 10210987648 10618937344 1141224512 12.227 100 76 149 1 1339590352 181591456 16774384
05:49:05 1799 8192000000 10240027280 10647171072 1155042144 12.357 100 84 163 0 1349831232 176252736 18536352
05:50:13 1940 8192000000 10260023872 10667270144 1167245104 12.471 100 83 163 3 1372084640 185970176 18869360
05:51:18 1635 8192000000 10316346832 10714800128 1173502288 12.530 100 91 176 0 1423194128 229718096 19973744
05:52:26 1055 8192000000 10342828896 10745982976 1184399888 12.631 100 92 179 2 1456929088 251307024 21222176
05:53:31 866 8192000000 10386613360 10813779968 1186799664 12.654 100 95 181 1 1497467232 288390320 22277248
05:54:36 940 8192000000 10444403584 10845093888 1193412160 12.715 100 90 174 0 1552616000 339194672 20009168
05:55:42 3074 8192000000 10472426912 10879741952 1199688464 12.773 100 83 162 1 1582649168 363704016 19256688
05:56:54 1564 8192000000 10504702416 10922844160 1195819536 12.737 100 96 191 0 1614582576 394575680 24187360
05:57:59 1366 8192000000 10577900688 10989621248 1204650768 12.820 100 96 192 1 1683919424 456656240 22612416
05:59:05 961 8192000000 10599336256 11028635648 1211250304 12.881 100 95 190 0 1705378352 470610864 23517184
06:00:09 1017 8192000000 10627233088 11074535424 1217692704 12.940 100 95 184 0 1736477216 494183936 24600576
06:01:15 1007 8192000000 10631492576 11121586176 1224807616 13.006 100 97 190 1 1736454480 488442112 23204752
06:02:18 2414 8192000000 10622869136 11047473152 1217544704 12.939 100 78 152 1 1726016208 490995184 17476320
06:03:24 897 8192000000 10619023840 11074048000 1206758272 12.839 100 97 182 2 1721853536 491301712 23793552
06:04:28 4565 8192000000 10588067216 11064676352 1201475360 12.790 100 57 112 1 1689358016 472846608 15036048
06:05:49 5979 8192000000 10604492304 11057373184 1196571200 12.744 100 58 115 0 1716534912 505281648 14682064
06:06:57 9007 8192000000 10594496896 11053498368 1176969952 12.562 100 50 96 1 1695000272 505644768 12385552
06:08:20 13290 8192000000 10529102496 10992189440 1171335904 12.509 100 47 95 1 1623783200 440497872 11949424
06:09:36 12467 8192000000 10437272624 10912727040 1161554080 12.418 100 53 106 0 1532929200 358422992 12952128
06:10:52 19251 8192000000 10283460848 10874544128 1138314976 12.200 100 31 62 0 1426574352 280097968 8161408
06:12:14 8174 8192000000 10201805088 10725376000 1144877056 12.261 100 46 89 1 1302103920 146935376 10291488
06:13:26 19787 8192000000 10151791680 10582966272 1144689936 12.260 100 57 107 1 1251449136 94355872 12403328
06:14:49 10585 8192000000 10160433440 10595241984 1132056064 12.141 99 34 68 1 1247388512 107851536 7480912
06:16:10 25842 8192000000 10187744800 10672824320 1134125232 12.160 99 15 30 1 1272968304 135322336 3520736
06:18:19 24570 8192000000 10109836560 10547625984 1124216240 12.067 98 32 60 1 1175825888 45458240 6151408
06:19:45 25395 8192000000 10046361040 10476228608 1077817696 11.627 95 39 75 1 1102938768 16739008 8382064
06:21:17 85159 8192000000 10064444416 10528309248 1095434912 11.794 97 23 46 1 1133222288 33527888 4259488
06:23:44 61469 8192000000 10095544944 10540404736 1110168368 11.934 98 23 44 2 1180150496 65951344 4030784
06:25:46 128388 8192000000 10070775152 10521350144 1104105184 11.877 99 21 40 2 1149000448 40386368 4508896
06:29:19 21511 8192000000 10065327344 10514386944 1094640960 11.787 98 35 71 0 1156219584 54797728 6780896
06:30:43 51484 8192000000 10068378048 10495483904 1114765520 11.978 100 26 50 0 1153799840 33258112 5776208
06:32:35 31454 8192000000 10092956128 10535051264 1128189792 12.104 100 34 68 0 1187096384 50968528 7938064
06:34:18 15052 8192000000 10149835184 10572795904 1149619904 12.306 100 70 135 1 1238055296 73812480 14622912
06:35:41 23176 8192000000 10201977584 10659553280 1149586304 12.306 100 38 75 0 1301859664 144103472 8169888
06:37:06 46895 8192000000 10165602384 10660114432 1148041088 12.291 100 21 42 0 1284502352 131001280 5459984
06:38:58 95533 8192000000 10220975520 10688258048 1151271904 12.321 100 14 28 0 1294115024 139460848 3382272
06:41:33 51572 8192000000 10143273152 10613575680 1116669840 11.996 98 21 44 0 1294557184 172159424 5727920
06:43:29 48940 8192000000 10100980128 10560028672 1133964240 12.159 100 21 42 1 1217108384 78511104 4633040
06:45:18 48250 8192000000 10103011712 10581782528 1129277536 12.115 99 33 66 0 1241488320 104970000 7240784
06:47:08 107569 8192000000 10100570480 10554167296 1081748192 11.664 95 29 58 1 1156581456 69274784 5558480
06:49:59 95565 8192000000 10082046352 10544513024 1096074256 11.800 96 11 22 0 1150165600 52075968 2015376
06:52:35 28217 8192000000 10090661456 10545627136 1125567808 12.080 99 42 78 1 1162301392 28429584 8304000
06:54:05 14453 8192000000 10138173840 10569048064 1139959184 12.215 99 56 105 5 1220484224 69687536 10837504
06:55:20 78908 8192000000 10158034384 10655281152 1154675696 12.353 100 37 74 0 1288467232 125609424 8182112
06:59:05 65864 8192000000 10073280048 10505146368 1109167232 11.925 98 30 60 0 1149615840 34430624 6017984
07:01:13 33679 8192000000 10108397184 10561646592 1124172400 12.066 99 44 84 1 1215311936 82247440 8892096
07:02:58 71441 8192000000 10077834704 10530701312 1109691568 11.929 97 24 48 1 1159642624 44341008 5610048
07:05:10 61547 8192000000 10082354512 10557362176 1123424128 12.059 99 14 28 2 1180699936 53818048 3457760
07:07:11 97593 8192000000 10119753232 10612105216 1123665936 12.062 99 15 29 1 1218983728 92229296 3088496
07:09:51 37488 8192000000 10052882528 10514341888 1109497312 11.928 99 25 51 1 1163453472 49305456 4650704
07:11:28 32034 8192000000 10127465232 10562441216 1106534560 11.900 98 53 102 0 1185207792 68146912 10526320
07:13:19 74650 8192000000 10123294192 10557038592 1116929072 11.998 99 28 56 1 1173308752 50413472 5966208
07:15:36 67052 8192000000 10069702496 10563858432 1111581456 11.947 99 13 26 1 1200082384 85520448 2980480
07:17:47 6937 8192000000 10121157488 10541780992 1130128080 12.123 100 60 119 1 1203133632 60970864 12034688
07:18:54 75156 8192000000 10108505632 10545168384 1141990976 12.234 100 19 38 0 1200659808 55247008 3421824
07:21:13 79861 8192000000 10124867680 10578165760 1111856896 11.950 97 17 34 2 1182903392 67431264 3615232
07:23:37 84948 8192000000 10083013584 10560122880 1136899824 12.186 99 24 48 0 1175006000 33584992 4521184
07:26:05 1431 8192000000 10070910576 10500784128 1142134464 12.236 100 63 107 1 1170738608 18015536 10588608
07:27:07 70990 8192000000 10113424368 10579025920 1138199600 12.199 99 9 18 0 1225551568 85608544 1743424
07:29:22 66155 8192000000 10091539408 10588893184 1091024912 11.752 95 14 28 0 1146003040 51739792 3238336
07:31:37 23646 8192000000 10057652736 10536816640 1107670272 11.910 99 15 29 0 1153052832 41595840 3786720
07:33:02 33209 8192000000 10038141920 10497781760 1111343824 11.945 99 25 49 1 1155835584 38986368 5505392
07:34:43 41781 8192000000 10091926944 10534084608 1099225392 11.830 97 29 58 0 1159077840 53776704 6075744
07:36:27 61635 8192000000 9419436976 9812996096 519295232 5.961 46 15 30 0 593125152 70739824 3090096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 30-05-2022 07:38:40 to 30-05-2022 07:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-05-2022 07:38:58 to 30-05-2022 07:39:00.

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          217538
MON$OLDEST_ACTIVE               218138
MON$OLDEST_SNAPSHOT             218138
MON$NEXT_TRANSACTION            218138
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                       2082592
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:194: 
:195: mscs-test-161.budzdorov.ru    Mon May 30 07:37:34 2022
:196:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:197: 
:198: 
:199: mscs-test-161.budzdorov.ru    Mon May 30 07:37:34 2022
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:201: 
--- end of diff output ---

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

30.05.22 07:39:01. 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-05-30 07:39:03 No severe PSQL-related problems occured
30.05.22 07:39:04. End of report.