Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.01.2023 01: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 15243352 kB
MemAvailable 30377008 kB
Buffers 0 kB
Cached 16445400 kB
SwapCached 4468 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-14 01:38:39
DTS_END                         2023-01-14 01:38:39.6500
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/35312
TRN_ID                          243347
ATT_ID                          671
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: 9444.67 22633 1133361
customer order: creation 176.98 348 21237
customer order: refuse 15.13 317 1815
order to supplier: creation 47.99 1268 5759
order to supplier: removal 3.49 87002 419
invoice (draft): creation 48.03 3120 5763
invoice (draft): removal 53.65 653 6438
invoice accept: apply 42.79 15893 5135
invoice accept: cancel 48.88 713 5865
customer reserve: creation 3915.98 159 469918
customer reserve: removal 4784.85 50 574182
realization accept: apply 85.28 158 10234
realization accept: cancel 89.41 91 10729
payment from customer: creation 61.16 96 7339
payment from customer: removal 5.99 63 719
payment to supplier: creation 49.17 113 5900
payment to supplier: removal 3.61 68 433
service: total inventory turnovers 5.01 1003 601
service: total monetary turnovers 6.46 41 775
service: aggregate perf. data 0.80 24194 96
service: refresh index statistics 0.03 317303 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-01-2023 01:39:25 to 14-01-2023 01:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1264
TEST_TIME 2 3537
TEST_TIME 3 4505
TEST_TIME 4 4498
TEST_TIME 5 4708
TEST_TIME 6 4924
TEST_TIME 7 5088
TEST_TIME 8 5879
TEST_TIME 9 6121
TEST_TIME 10 6452
TEST_TIME 11 6866
TEST_TIME 12 7125
TEST_TIME 13 7469
TEST_TIME 14 7745
TEST_TIME 15 7753
TEST_TIME 16 8498
TEST_TIME 17 8294
TEST_TIME 18 8551
TEST_TIME 19 8366
TEST_TIME 20 9131
TEST_TIME 21 9137
TEST_TIME 22 9562
TEST_TIME 23 9564
TEST_TIME 24 9776
TEST_TIME 25 9876
TEST_TIME 26 10062
TEST_TIME 27 10195
TEST_TIME 28 10422
TEST_TIME 29 10758
TEST_TIME 30 10623
TEST_TIME 31 10667
TEST_TIME 32 10468
TEST_TIME 33 10669
TEST_TIME 34 10508
TEST_TIME 35 10411
TEST_TIME 36 10627
TEST_TIME 37 10693
TEST_TIME 38 10440
TEST_TIME 39 10209
TEST_TIME 40 10019
TEST_TIME 41 9998
TEST_TIME 42 10012
TEST_TIME 43 9855
TEST_TIME 44 9793
TEST_TIME 45 9807
TEST_TIME 46 9705
TEST_TIME 47 9606
TEST_TIME 48 9748
TEST_TIME 49 9742
TEST_TIME 50 9686
TEST_TIME 51 9549
TEST_TIME 52 9584
TEST_TIME 53 9508
TEST_TIME 54 9462
TEST_TIME 55 9378
TEST_TIME 56 9346
TEST_TIME 57 9243
TEST_TIME 58 9236
TEST_TIME 59 9204
TEST_TIME 60 9274
TEST_TIME 61 9258
TEST_TIME 62 9296
TEST_TIME 63 9355
TEST_TIME 64 9387
TEST_TIME 65 9346
TEST_TIME 66 9405
TEST_TIME 67 9439
TEST_TIME 68 9541
TEST_TIME 69 9512
TEST_TIME 70 9473
TEST_TIME 71 9543
TEST_TIME 72 9630
TEST_TIME 73 9615
TEST_TIME 74 9551
TEST_TIME 75 9494
TEST_TIME 76 9531
TEST_TIME 77 9436
TEST_TIME 78 9500
TEST_TIME 79 9428
TEST_TIME 80 9406
TEST_TIME 81 9343
TEST_TIME 82 9364
TEST_TIME 83 9350
TEST_TIME 84 9383
TEST_TIME 85 9347
TEST_TIME 86 9330
TEST_TIME 87 9454
TEST_TIME 88 9460
TEST_TIME 89 9440
TEST_TIME 90 9464
TEST_TIME 91 9504
TEST_TIME 92 9551
TEST_TIME 93 9568
TEST_TIME 94 9626
TEST_TIME 95 9616
TEST_TIME 96 9657
TEST_TIME 97 9672
TEST_TIME 98 9580
TEST_TIME 99 9600
TEST_TIME 100 9533
TEST_TIME 101 9514
TEST_TIME 102 9521
TEST_TIME 103 9476
TEST_TIME 104 9472
TEST_TIME 105 9449
TEST_TIME 106 9409
TEST_TIME 107 9362
TEST_TIME 108 9341
TEST_TIME 109 9396
TEST_TIME 110 9342
TEST_TIME 111 9375
TEST_TIME 112 9379
TEST_TIME 113 9363
TEST_TIME 114 9379
TEST_TIME 115 9389
TEST_TIME 116 9403
TEST_TIME 117 9368
TEST_TIME 118 9425
TEST_TIME 119 9497
TEST_TIME 120 9445

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-01-2023 01:39:29 to 14-01-2023 01:39:32.

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 1570471 1570471 0 0.00 0 19930 5 0 0
doc_list_biud 1570470 1570470 0 0.00 0 24537 23 0 0
sp_add_invoice_to_stock 5254 5135 119 2.26 29 174349 15893 0 119
sp_cancel_adding_invoice 5865 5865 0 0.00 4 61397 713 0 0
sp_cancel_client_order 1815 1815 0 0.00 4 6841 317 0 0
sp_cancel_customer_reserve 574182 574182 0 0.00 0 24540 51 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 2 3460 63 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 5844 69 0 0
sp_cancel_supplier_invoice 6447 6438 9 0.13 1 16285 653 0 9
sp_cancel_supplier_order 419 419 0 0.00 5 801495 87002 0 0
sp_cancel_write_off 10729 10729 0 0.00 1 9738 91 0 0
sp_client_order 21237 21237 0 0.00 3 16451 348 0 0
sp_customer_reserve 469918 469918 0 0.00 0 45949 159 0 0
sp_fill_shopping_cart 32750 32750 0 0.00 1 13586 40 0 0
sp_fill_shopping_cart_clo_res 469916 469916 0 0.00 0 14795 13 0 0
sp_get_clo_for_invoice 5131 5131 0 0.00 0 31926 315 0 0
sp_kill_cost_storno 17746 17746 0 0.00 0 9736 46 0 0
sp_kill_qstorno_ret_qs2qd 1165950 1165950 0 0.00 0 24534 27 0 0
sp_kill_qty_storno 614812 614812 0 0.00 0 24537 59 0 0
sp_lock_selected_doc 21318 21318 0 0.00 0 5661 4 0 0
sp_make_cost_storno 28604 28604 0 0.00 0 18654 45 0 0
sp_multiply_rows_for_pdistr 28603 28603 0 0.00 0 7556 23 0 0
sp_multiply_rows_for_qdistr 21237 21237 0 0.00 0 9199 148 0 0
sp_pay_from_customer 7339 7339 0 0.00 3 13263 96 0 0
sp_pay_to_supplier 5900 5900 0 0.00 4 18662 114 0 0
sp_payment_common 13239 13239 0 0.00 1 12166 32 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 0 6802 293 0 0
sp_qd_handle_on_invoice_upd_sts 10996 10996 0 0.00 0 14378 289 0 0
sp_qd_handle_on_reserve_upd_sts 10233 10233 0 0.00 0 12261 51 0 0
sp_reserve_write_off 10234 10234 0 0.00 6 13408 159 0 0
sp_supplier_invoice 5763 5763 0 0.00 6 98060 3120 0 0
sp_supplier_order 5759 5759 0 0.00 5 29105 1268 0 0
srv_aggregate_perf_data 96 96 0 0.00 2784 371255 24195 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2799 101868 42170 0 0
srv_find_qd_qs_mism 1085450 1085450 0 0.00 0 19929 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 34 39183 1003 0 0
srv_make_money_saldo 775 775 0 0.00 1 5590 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 125583 568278 317304 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5135 5135 0 0.00 0 10068 146 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 3369 88 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 6722 18 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 3928 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 1237 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 1670 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 632 16 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 18880 1552 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 2251 10 0 0
t$perf-norm:sp_client_order 21237 21237 0 0.00 0 12343 17 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 2100 6 0 0
t$perf-norm:sp_pay_from_customer 7339 7339 0 0.00 0 11397 11 0 0
t$perf-norm:sp_pay_to_supplier 5900 5900 0 0.00 0 8989 14 0 0
t$perf-norm:sp_reserve_write_off 10234 10234 0 0.00 0 12338 14 0 0
t$perf-norm:sp_supplier_invoice 5763 5763 0 0.00 0 5209 17 0 0
t$perf-norm:sp_supplier_order 5759 5759 0 0.00 0 6635 14 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 505 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 174 3 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 4137 23 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 3071 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10498 10260 238 2.26 0 7301 21 0 238
v_all_customers 39102 39102 0 0.00 0 18 1 0 0
v_all_suppliers 31576 31576 0 0.00 0 16 1 0 0
v_all_wares 42474 42474 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2280 9 0 0
v_cancel_client_order 3630 3630 0 0.00 0 3789 7 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 914 4 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 3047 9 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 4588 12 0 0
v_cancel_supplier_invoice 804 786 18 2.23 0 4990 22 0 18
v_cancel_supplier_order 838 838 0 0.00 0 1562 6 0 0
v_cancel_write_off 2080 2080 0 0.00 0 6579 20 0 0
v_max_id_clo_ord 5758 5758 0 0.00 0 3944 2 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 2466 13 0 0
v_max_id_ord_sup 5754 5754 0 0.00 0 6843 7 0 0
v_max_non_paid_invoice 1623 1623 0 0.00 0 3324 5 0 0
v_max_non_paid_realizn 3727 3727 0 0.00 0 1919 4 0 0
v_min_id_clo_ord 5758 5758 0 0.00 0 1480 4 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 7404 17 0 0
v_min_id_ord_sup 5754 5754 0 0.00 0 7523 8 0 0
v_min_non_paid_invoice 5900 5900 0 0.00 0 8603 21 0 0
v_min_non_paid_realizn 7339 7339 0 0.00 0 11209 25 0 0
v_our_firm 10596 10596 0 0.00 0 16 1 0 0
v_reserve_write_off 20466 20466 0 0.00 0 4646 7 0 0
x_lock_depdocs_on_canc_invoice 11910 11910 0 0.00 0 14886 104 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 6010 300 0 0
x_make_qstorno_1000_1200 5759 5759 0 0.00 1 29074 1197 0 0
x_make_qstorno_1200_2000 5763 5763 0 0.00 2 98046 2968 0 0
x_make_qstorno_2100_3300 469918 469918 0 0.00 0 45947 144 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-01-2023 01:39:33 to 14-01-2023 01:39: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
23:08:43 8 8192000000 8802120704 8814297088 7908448 0.096 2 2 3 0 8049536 66144 74944
23:09:44 31669 8192000000 9899031408 10209587200 981400768 10.698 100 87 174 1 1010415984 5948688 23066528
23:11:19 18411 8192000000 9942248064 10261880832 1024206096 11.113 100 89 178 0 1055786080 8362064 23217920
23:12:42 14022 8192000000 9963931488 10292322304 1045059664 11.313 100 83 166 0 1075935760 9820240 21055856
23:14:12 7979 8192000000 10023974960 10370883584 1117277456 12.001 100 75 146 2 1145805344 10978672 17549216
23:15:24 4672 8192000000 10084295568 10441932800 1171887712 12.514 100 84 167 1 1204722672 13855232 18979728
23:16:32 3135 8192000000 10125578576 10492825600 1212761856 12.895 100 76 152 1 1243240704 13178304 17300544
23:17:37 1636 8192000000 10156827616 10529595392 1245963440 13.201 100 83 165 1 1277418256 13036368 18418448
23:18:42 2029 8192000000 10176585552 10562441216 1271510144 13.435 100 73 144 1 1300705792 13032544 16163104
23:19:46 1250 8192000000 10202675824 10586292224 1292205776 13.624 100 87 172 1 1325152832 13011248 19935808
23:20:50 16460 8192000000 10201201520 10594041856 1300422864 13.699 100 61 122 0 1324810064 11008448 13378752
23:22:07 920 8192000000 10209141424 10606919680 1307386736 13.762 100 63 127 0 1329056400 6923600 14746064
23:23:18 5916 8192000000 10202930304 10615091200 1314911232 13.831 100 26 52 1 1331412304 10980640 5520432
23:24:26 5995 8192000000 10214917088 10630262784 1325226432 13.924 100 20 40 1 1338918288 9445232 4246624
23:25:35 4480 8192000000 10213662016 10631680000 1327107424 13.941 100 24 50 1 1338890480 6438240 5344816
23:26:40 50383 8192000000 10227506016 10644049920 1332141264 13.986 100 26 52 0 1343912752 5570016 6201472
23:28:51 4850 8192000000 10198609152 10618130432 1296382576 13.662 98 26 51 0 1308619392 6325920 5910896
23:29:57 9257 8192000000 10165223776 10576502784 1212944672 12.896 93 15 30 0 1220909472 5037696 2927104
23:31:06 6475 8192000000 10140822416 10542641152 1243325472 13.177 99 27 54 1 1255480784 5731056 6424256
23:32:15 37839 8192000000 10131770576 10522189824 1202511568 12.800 96 44 85 1 1218036320 5740064 9784688
23:33:54 3262 8192000000 10158758928 10558574592 1257959264 13.311 100 30 59 0 1275736944 11587504 6190176
23:34:58 9438 8192000000 10163818864 10571866112 1265278432 13.378 99 27 54 1 1277271696 5856432 6136832
23:36:09 13050 8192000000 10178039456 10591772672 1289299728 13.598 100 20 41 0 1299607040 5130992 5176320
23:37:23 39001 8192000000 10185707040 10603536384 1298597712 13.682 100 23 47 0 1308754448 4831216 5325520
23:39:04 30814 8192000000 10226269072 10636304384 1323736448 13.911 100 36 72 1 1336815744 5387872 7691424
23:40:40 6344 8192000000 10261830224 10665779200 1347207520 14.122 100 59 118 0 1373187136 13234176 12745440
23:41:52 15312 8192000000 10276159072 10689822720 1363230080 14.266 100 49 98 0 1387768832 13103936 11434816
23:43:10 43421 8192000000 10285283280 10708074496 1376296592 14.383 100 32 64 0 1398656944 14298144 8062208
23:44:58 7014 8192000000 10300999584 10719465472 1386897120 14.478 100 59 117 0 1416967968 16372464 13698384
23:46:10 16603 8192000000 10294990592 10726871040 1389127312 14.498 100 44 87 0 1419105760 19215072 10763376
23:47:32 4973 8192000000 10318045456 10735710208 1385626000 14.467 99 66 132 0 1419062288 17847008 15589280
23:48:50 8338 8192000000 10303953488 10718822400 1379629104 14.413 100 63 124 0 1416929456 22266976 15033376
23:50:08 4479 8192000000 10333553376 10748764160 1398114032 14.578 100 66 131 0 1443813040 29569936 16129072
23:51:19 3719 8192000000 10334874928 10747895808 1401550912 14.609 100 65 126 1 1440676016 23819632 15305472
23:52:30 8255 8192000000 10344833120 10771861504 1414701600 14.726 100 54 106 0 1454543904 26816816 13025488
23:53:45 9966 8192000000 10333092768 10762461184 1396122128 14.560 99 32 64 0 1431744560 27342096 8280336
23:54:57 10446 8192000000 10307501392 10736463872 1384630304 14.458 100 34 68 0 1413427664 20063488 8733872
23:56:19 3173 8192000000 10326734880 10741383168 1388737904 14.495 100 76 150 0 1433888224 26337648 18812672
23:57:24 6114 8192000000 10325809456 10743672832 1386176608 14.472 100 56 110 1 1427840064 27505872 14157584
23:58:37 3920 8192000000 10321835232 10737758208 1376060544 14.381 100 60 116 1 1423399008 32371856 14966608
23:59:44 2798 8192000000 10293692144 10716368896 1359496048 14.233 100 37 74 1 1395253456 25544608 10212800
00:00:50 5507 8192000000 10294083840 10719391744 1350748112 14.154 99 25 49 0 1387758528 29983792 7026624
00:02:01 16750 8192000000 10297008832 10734960640 1369472144 14.322 100 35 70 0 1408279120 29454832 9352144
00:03:20 21567 8192000000 10310613552 10732253184 1361495088 14.251 99 45 87 2 1404064464 31734640 10834736
00:04:49 25931 8192000000 10278626816 10714693632 1348533168 14.134 99 19 38 0 1379945856 26989264 4423424
00:06:48 31099 8192000000 10265382784 10709147648 1326839344 13.939 99 15 30 1 1355950480 25632864 3478272
00:08:26 66826 8192000000 10279529072 10710360064 1348272720 14.132 99 19 38 0 1366753760 13927424 4553616
00:10:54 92472 8192000000 10282395312 10715901952 1329867264 13.966 97 12 24 0 1350349264 17915856 2566144
00:13:28 62040 8192000000 10270200768 10724524032 1315352976 13.835 96 12 24 0 1332987376 15254720 2379680
00:15:31 72837 8192000000 10282811536 10732593152 1362609472 14.261 99 9 18 0 1375496096 11108320 1778304
00:18:18 23581 8192000000 10287957296 10734743552 1379697136 14.414 100 43 82 0 1396629792 6904432 10028224
00:19:43 48550 8192000000 10295508656 10751262720 1387572384 14.484 100 37 73 0 1406610768 10519616 8518768
00:21:41 63342 8192000000 10311907456 10760486912 1393196864 14.534 100 27 55 0 1413721264 13660352 6864048
00:23:46 73171 8192000000 10307196864 10765369344 1399759456 14.593 100 12 23 0 1415978000 13680096 2538448
00:26:01 34561 8192000000 10289165984 10757177344 1380033376 14.417 99 4 8 0 1399991584 18641232 1316976
00:27:43 38844 8192000000 10292546768 10749722624 1386475008 14.474 100 14 29 0 1400322640 10358880 3488752
00:30:32 63821 8192000000 10272230880 10745257984 1352861328 14.173 98 13 26 0 1369314640 13104608 3348704
00:32:36 47265 8192000000 10283169472 10745225216 1363074544 14.265 98 24 48 1 1382649936 13304192 6271200
00:34:31 62198 8192000000 10275056080 10745348096 1369533008 14.323 99 5 10 0 1384487312 13874448 1079856
00:36:41 66517 8192000000 10272835264 10730098688 1355106160 14.193 99 11 22 1 1364845136 7511648 2227328
00:38:48 47923 8192000000 10290902032 10752229376 1357831184 14.218 98 13 28 1 1371178688 10452816 2894688
00:40:38 77238 8192000000 10271124736 10730205184 1302870912 13.721 94 16 31 0 1324582352 18176384 3535056
00:42:56 95341 8192000000 10293814608 10747502592 1325829088 13.929 96 19 36 0 1353843408 23743232 4271088
00:45:32 9504 8192000000 10292109632 10727899136 1366777600 14.298 100 43 85 0 1384256704 8567008 8912096
00:46:44 49391 8192000000 10256132320 10702688256 1316576928 13.846 97 15 30 1 1329150048 9337920 3235200
00:48:36 24753 8192000000 10284502544 10725142528 1354019872 14.184 99 42 84 0 1378672928 15066880 9586176
00:50:01 61458 8192000000 10276078896 10712727552 1311707296 13.802 96 9 18 0 1325841616 11918304 2216016
00:52:04 89851 8192000000 10247771520 10690990080 1289706480 13.602 95 25 50 0 1304581424 9641840 5233104
00:54:39 68044 8192000000 10259869808 10714193920 1337185216 14.032 99 19 38 0 1357944992 16600592 4159184
00:56:48 50087 8192000000 10255535872 10715189248 1355361200 14.196 100 12 25 1 1369037968 10978800 2697968
00:58:50 71169 8192000000 10255526288 10704621568 1320461920 13.881 98 14 28 0 1340071552 16268608 3341024
01:01:17 50791 8192000000 10233407488 10689748992 1287814704 13.584 96 10 20 0 1307826176 17998080 2013392
01:03:14 59707 8192000000 10263494528 10706317312 1328428256 13.953 98 11 22 0 1344217040 13189472 2599312
01:05:21 37002 8192000000 10262274944 10715684864 1342594288 14.081 99 16 32 1 1362184192 15463792 4126112
01:07:04 38497 8192000000 10273572192 10717749248 1364397712 14.277 100 27 55 1 1379114384 8947472 5769200
01:08:48 43826 8192000000 10290310064 10745499648 1372562144 14.350 100 14 28 0 1388086688 12687712 2836832
01:10:33 55603 8192000000 10266320352 10721497088 1341881232 14.074 98 16 30 0 1360895040 16114720 2899088
01:12:30 64212 8192000000 10281342720 10729545728 1333795552 14.001 97 8 16 0 1353637056 18063584 1777920
01:14:35 93154 8192000000 10293454720 10745069568 1361709376 14.253 98 18 34 2 1378118800 12159520 4249904
01:17:17 101868 8192000000 10303496944 10767028224 1389850240 14.505 100 35 70 0 1413400640 15259552 8290848
01:20:00 72976 8192000000 10283996688 10751254528 1377705216 14.396 99 15 30 0 1392898608 11777776 3415616
01:22:14 41980 8192000000 10284693776 10747101184 1370732768 14.334 99 13 27 0 1388828368 14717584 3378016
01:23:58 91272 8192000000 10306577280 10755059712 1363845008 14.272 98 26 52 0 1385353232 14998528 6509696
01:26:54 16670 8192000000 10297136016 10762215424 1389834016 14.504 100 35 69 2 1414400064 16292496 8273552
01:28:16 49864 8192000000 10262727872 10717110272 1333018032 13.994 96 13 26 0 1347524336 11483184 3023120
01:30:40 76390 8192000000 10283531200 10741010432 1354853424 14.191 98 11 22 0 1368846816 11474960 2518432
01:32:58 24999 8192000000 10259490000 10715897856 1316022560 13.841 96 23 47 0 1333720976 12468096 5230320
01:34:34 33747 8192000000 10274569776 10727350272 1347168288 14.122 99 30 60 0 1370660368 16664608 6827472
01:36:13 32443 8192000000 10279421504 10734555136 1369473760 14.322 100 20 40 0 1389515408 15515920 4525728
01:37:46 50854 8192000000 10251889232 10689228800 1287103632 13.578 94 18 37 0 1299763792 8596736 4063424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-01-2023 01:39:56 to 14-01-2023 01:39:58.

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          242337
MON$OLDEST_ACTIVE               244054
MON$OLDEST_SNAPSHOT             244054
MON$NEXT_TRANSACTION            244054
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                       2037472
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 ---
:173: 
:174: mscs-test-161.budzdorov.ru    Sat Jan 14 01:38:41 2023
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:176: 
:177: 
:178: mscs-test-161.budzdorov.ru    Sat Jan 14 01:38:41 2023
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Sat Jan 14 01:38:42 2023
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:184: 
:185: 
:186: mscs-test-161.budzdorov.ru    Sat Jan 14 01:38:46 2023
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:188: 
--- end of diff output ---

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

14.01.23 01:39:59. 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-14 01:40:02 No severe PSQL-related problems occured
14.01.23 01:40:03. End of report.