Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2021 16:40
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 16411396 kB
MemAvailable 31392272 kB
Buffers 0 kB
Cached 15289080 kB
SwapCached 440 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.9
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 2021-12-29 16:38:01
DTS_END                         2021-12-29 16:38:01.2030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57310
TRN_ID                          245037
ATT_ID                          670
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: 9646.04 20311 1157525
customer order: creation 178.87 309 21464
customer order: refuse 15.33 322 1840
order to supplier: creation 47.60 1216 5712
order to supplier: removal 3.44 111146 413
invoice (draft): creation 47.26 3292 5671
invoice (draft): removal 53.92 839 6470
invoice accept: apply 44.34 18521 5321
invoice accept: cancel 48.82 813 5858
customer reserve: creation 4127.90 182 495348
customer reserve: removal 4775.23 62 573027
realization accept: apply 87.07 189 10448
realization accept: cancel 80.07 122 9608
payment from customer: creation 62.08 92 7450
payment from customer: removal 5.81 88 697
payment to supplier: creation 51.03 118 6124
payment to supplier: removal 3.43 59 411
service: total inventory turnovers 5.56 906 667
service: total monetary turnovers 7.42 36 890
service: aggregate perf. data 0.85 19253 102
service: refresh index statistics 0.03 248647 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2021 16:40:36 to 29-12-2021 16:40:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6448
TEST_TIME 2 5864
TEST_TIME 3 6003
TEST_TIME 4 5503
TEST_TIME 5 5596
TEST_TIME 6 5801
TEST_TIME 7 6063
TEST_TIME 8 6433
TEST_TIME 9 6650
TEST_TIME 10 6668
TEST_TIME 11 7839
TEST_TIME 12 8011
TEST_TIME 13 7973
TEST_TIME 14 8005
TEST_TIME 15 8513
TEST_TIME 16 8483
TEST_TIME 17 8753
TEST_TIME 18 9024
TEST_TIME 19 8868
TEST_TIME 20 9154
TEST_TIME 21 9159
TEST_TIME 22 9169
TEST_TIME 23 9788
TEST_TIME 24 9941
TEST_TIME 25 10023
TEST_TIME 26 10152
TEST_TIME 27 10631
TEST_TIME 28 11777
TEST_TIME 29 11573
TEST_TIME 30 11365
TEST_TIME 31 11581
TEST_TIME 32 11350
TEST_TIME 33 11283
TEST_TIME 34 11148
TEST_TIME 35 11304
TEST_TIME 36 11210
TEST_TIME 37 11065
TEST_TIME 38 10928
TEST_TIME 39 10753
TEST_TIME 40 10750
TEST_TIME 41 10681
TEST_TIME 42 10638
TEST_TIME 43 10507
TEST_TIME 44 10493
TEST_TIME 45 10417
TEST_TIME 46 10256
TEST_TIME 47 10477
TEST_TIME 48 10459
TEST_TIME 49 10388
TEST_TIME 50 10384
TEST_TIME 51 10381
TEST_TIME 52 10428
TEST_TIME 53 10407
TEST_TIME 54 10328
TEST_TIME 55 10360
TEST_TIME 56 10303
TEST_TIME 57 10403
TEST_TIME 58 10362
TEST_TIME 59 10490
TEST_TIME 60 10531
TEST_TIME 61 10538
TEST_TIME 62 10597
TEST_TIME 63 10568
TEST_TIME 64 10463
TEST_TIME 65 10362
TEST_TIME 66 10387
TEST_TIME 67 10355
TEST_TIME 68 10307
TEST_TIME 69 10286
TEST_TIME 70 10297
TEST_TIME 71 10246
TEST_TIME 72 10190
TEST_TIME 73 10122
TEST_TIME 74 10093
TEST_TIME 75 10033
TEST_TIME 76 10101
TEST_TIME 77 10038
TEST_TIME 78 10007
TEST_TIME 79 9980
TEST_TIME 80 9943
TEST_TIME 81 10059
TEST_TIME 82 10056
TEST_TIME 83 10012
TEST_TIME 84 10094
TEST_TIME 85 10099
TEST_TIME 86 10145
TEST_TIME 87 10100
TEST_TIME 88 10121
TEST_TIME 89 10149
TEST_TIME 90 10128
TEST_TIME 91 10149
TEST_TIME 92 10045
TEST_TIME 93 10058
TEST_TIME 94 10011
TEST_TIME 95 9967
TEST_TIME 96 9903
TEST_TIME 97 9905
TEST_TIME 98 9909
TEST_TIME 99 9862
TEST_TIME 100 9846
TEST_TIME 101 9847
TEST_TIME 102 9832
TEST_TIME 103 9826
TEST_TIME 104 9868
TEST_TIME 105 9902
TEST_TIME 106 9885
TEST_TIME 107 9881
TEST_TIME 108 9866
TEST_TIME 109 9956
TEST_TIME 110 9924
TEST_TIME 111 9895
TEST_TIME 112 9916
TEST_TIME 113 9886
TEST_TIME 114 9818
TEST_TIME 115 9844
TEST_TIME 116 9791
TEST_TIME 117 9808
TEST_TIME 118 9755
TEST_TIME 119 9728
TEST_TIME 120 9647

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-12-2021 16:40:40 to 29-12-2021 16:40:43.

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 1614638 1614638 0 0.00 0 17013 6 0 0
doc_list_biud 1614638 1614638 0 0.00 0 31265 28 0 0
sp_add_invoice_to_stock 5433 5321 112 2.06 39 226784 18521 0 112
sp_cancel_adding_invoice 5858 5858 0 0.00 4 77200 813 0 0
sp_cancel_client_order 1840 1840 0 0.00 4 17079 323 0 0
sp_cancel_customer_reserve 573027 573027 0 0.00 1 31268 62 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 5983 88 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 1351 60 0 0
sp_cancel_supplier_invoice 6479 6470 9 0.13 2 16240 840 0 9
sp_cancel_supplier_order 413 413 0 0.00 7 936746 111146 0 0
sp_cancel_write_off 9608 9608 0 0.00 1 17064 122 0 0
sp_client_order 21464 21464 0 0.00 3 19954 309 0 0
sp_customer_reserve 495348 495348 0 0.00 0 124064 183 0 0
sp_fill_shopping_cart 32845 32845 0 0.00 1 10027 36 0 0
sp_fill_shopping_cart_clo_res 495346 495346 0 0.00 0 17220 13 0 0
sp_get_clo_for_invoice 5319 5319 0 0.00 0 23056 297 0 0
sp_kill_cost_storno 16574 16574 0 0.00 0 17009 63 0 0
sp_kill_qstorno_ret_qs2qd 1162545 1162545 0 0.00 0 31239 34 0 0
sp_kill_qty_storno 612982 612982 0 0.00 0 31265 73 0 0
sp_lock_selected_doc 21703 21703 0 0.00 0 9078 7 0 0
sp_make_cost_storno 29340 29340 0 0.00 0 9530 50 0 0
sp_multiply_rows_for_pdistr 29340 29340 0 0.00 0 7417 28 0 0
sp_multiply_rows_for_qdistr 21464 21464 0 0.00 0 16918 144 0 0
sp_pay_from_customer 7450 7450 0 0.00 4 9839 92 0 0
sp_pay_to_supplier 6124 6124 0 0.00 4 17193 118 0 0
sp_payment_common 13574 13574 0 0.00 1 17169 27 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 0 17068 304 0 0
sp_qd_handle_on_invoice_upd_sts 11176 11176 0 0.00 0 14838 345 0 0
sp_qd_handle_on_reserve_upd_sts 10448 10448 0 0.00 0 16676 65 0 0
sp_reserve_write_off 10448 10448 0 0.00 6 16688 189 0 0
sp_supplier_invoice 5671 5671 0 0.00 6 91745 3292 0 0
sp_supplier_order 5712 5712 0 0.00 5 25057 1217 0 0
srv_aggregate_perf_data 102 102 0 0.00 2717 145244 19253 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1136 130151 44074 0 0
srv_find_qd_qs_mism 1106470 1106470 0 0.00 0 13606 5 0 0
srv_make_invnt_saldo 667 667 0 0.00 45 59708 906 0 0
srv_make_money_saldo 890 890 0 0.00 1 5597 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 148143 339729 248648 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 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 5321 5321 0 0.00 0 29799 187 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 1546 69 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 4141 13 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 1699 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2192 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 733 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1022 16 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 36656 1886 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 2267 5 0 0
t$perf-norm:sp_client_order 21464 21464 0 0.00 0 19129 22 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 2899 10 0 0
t$perf-norm:sp_pay_from_customer 7450 7450 0 0.00 0 14994 16 0 0
t$perf-norm:sp_pay_to_supplier 6124 6124 0 0.00 0 14774 13 0 0
t$perf-norm:sp_reserve_write_off 10448 10448 0 0.00 0 11501 17 0 0
t$perf-norm:sp_supplier_invoice 5671 5671 0 0.00 0 15094 23 0 0
t$perf-norm:sp_supplier_order 5712 5712 0 0.00 0 5042 19 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 86 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1790 32 0 0
t$perf-norm:srv_make_invnt_saldo 667 667 0 0.00 0 3517 33 0 0
t$perf-norm:srv_make_money_saldo 890 890 0 0.00 0 2704 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 74 22 0 0
v_add_invoice_to_stock 10860 10636 224 2.06 0 7029 17 0 224
v_all_customers 39128 39128 0 0.00 0 18 1 0 0
v_all_suppliers 31524 31524 0 0.00 0 23 1 0 0
v_all_wares 42928 42928 0 0.00 0 160 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 1860 8 0 0
v_cancel_client_order 3680 3680 0 0.00 0 3459 4 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 1428 5 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 2368 5 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 736 6 0 0
v_cancel_supplier_invoice 862 844 18 2.08 0 8972 27 0 18
v_cancel_supplier_order 826 826 0 0.00 0 907 5 0 0
v_cancel_write_off 2058 2058 0 0.00 0 6540 16 0 0
v_max_id_clo_ord 5712 5712 0 0.00 0 143 1 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 2216 12 0 0
v_max_id_ord_sup 5669 5669 0 0.00 0 6898 6 0 0
v_max_non_paid_invoice 1743 1743 0 0.00 0 1799 3 0 0
v_max_non_paid_realizn 3917 3917 0 0.00 0 1644 5 0 0
v_min_id_clo_ord 5712 5712 0 0.00 0 3783 8 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 4039 12 0 0
v_min_id_ord_sup 5669 5669 0 0.00 0 3934 6 0 0
v_min_non_paid_invoice 6124 6124 0 0.00 0 17163 22 0 0
v_min_non_paid_realizn 7450 7450 0 0.00 0 9795 19 0 0
v_our_firm 10866 10866 0 0.00 0 20 1 0 0
v_reserve_write_off 20896 20896 0 0.00 0 5462 5 0 0
x_lock_depdocs_on_canc_invoice 11906 11906 0 0.00 0 15662 133 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 20449 550 0 0
x_make_qstorno_1000_1200 5712 5712 0 0.00 2 25008 1133 0 0
x_make_qstorno_1200_2000 5671 5671 0 0.00 3 91734 3175 0 0
x_make_qstorno_2100_3300 495348 495348 0 0.00 0 124063 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2021 16:40:44 to 29-12-2021 16:40:48.

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
14:08:04 6 8192000000 8806374176 8820072448 11407392 0.139 3 3 4 0 11592752 78240 107120
14:09:06 28321 8192000000 9898419952 10208940032 970740288 10.594 100 88 176 0 999847760 5479696 23627776
14:10:38 27024 8192000000 9947911200 10270240768 1023219904 11.103 100 89 178 0 1054434736 8221744 22993088
14:12:10 15650 8192000000 9986720832 10327269376 1072349040 11.575 100 76 152 1 1100830064 9379328 19101696
14:13:29 15289 8192000000 10059794640 10412785664 1141328432 12.228 100 78 156 0 1170868256 11399488 18140336
14:14:50 9108 8192000000 10099301584 10463571968 1187605552 12.661 100 85 168 0 1220398480 13140016 19652912
14:16:02 6346 8192000000 10133806512 10508111872 1229233184 13.047 100 78 156 0 1259289376 12645952 17410240
14:17:10 2589 8192000000 10172799216 10552537088 1260358800 13.333 100 82 164 2 1291625792 13098144 18168848
14:18:14 1757 8192000000 10208842080 10590773248 1290476016 13.609 100 87 175 1 1322612048 12661392 19474640
14:19:16 15840 8192000000 10200351472 10596732928 1298819408 13.685 100 68 131 3 1322433360 6866224 16747728
14:20:33 6330 8192000000 10206163232 10615750656 1314265488 13.825 100 26 52 2 1327150816 6939072 5946256
14:21:40 8239 8192000000 10207370896 10626531328 1324973504 13.922 100 17 34 0 1335050512 6152576 3924432
14:22:48 70735 8192000000 10219698304 10641338368 1329823856 13.966 100 29 56 2 1341796736 5354352 6618528
14:25:03 4698 8192000000 10239013936 10656374784 1339976960 14.057 100 33 66 1 1354222784 6765504 7480320
14:26:10 6188 8192000000 10226523872 10649153536 1335573808 14.017 100 24 49 1 1346999088 5974784 5450496
14:27:19 63382 8192000000 10183227408 10593099776 1238524848 13.133 93 25 50 0 1249560752 4187744 6848160
14:29:23 6025 8192000000 10170956592 10575945728 1247288096 13.213 97 28 56 2 1260819520 7356128 6175296
14:30:33 6059 8192000000 10145954912 10548224000 1244805792 13.190 99 25 50 1 1256521744 5616800 6099152
14:31:42 9492 8192000000 10144535504 10549735424 1234796000 13.098 98 15 30 2 1244060096 5273520 3990576
14:32:54 25722 8192000000 10160141184 10569711616 1264522848 13.371 99 34 69 1 1278151536 4982256 8646432
14:34:23 8331 8192000000 10178086096 10593267712 1291221408 13.615 100 19 38 0 1301568816 5930144 4417264
14:35:33 7093 8192000000 10200332128 10610429952 1303273840 13.725 100 22 45 1 1313362656 4960832 5127984
14:36:41 15881 8192000000 10204052544 10617868288 1310889792 13.794 100 28 56 2 1323993232 6315088 6788352
14:37:57 45694 8192000000 10228120224 10641346560 1324546240 13.918 100 33 64 1 1336629568 5859440 6223888
14:39:50 7359 8192000000 10274035680 10681487360 1357597632 14.216 100 64 127 2 1383330880 12104896 13628352
14:41:02 31692 8192000000 10274942240 10702442496 1372065408 14.346 100 41 80 1 1395727440 14353888 9308144
14:42:38 11020 8192000000 10291843280 10714034176 1380632912 14.422 100 40 80 1 1403073280 13146640 9293728
14:43:54 8843 8192000000 10307136624 10727079936 1386902160 14.478 100 53 102 1 1414218672 15956048 11360464
14:45:06 3865 8192000000 10295913520 10712227840 1377972912 14.398 99 57 113 3 1407205584 15069904 14162768
14:46:14 13055 8192000000 10309906976 10735677440 1392726016 14.530 100 53 105 2 1428604192 22435920 13442256
14:47:33 4692 8192000000 10320793296 10743713792 1393620224 14.538 100 50 100 2 1429517840 23147808 12749808
14:48:42 4147 8192000000 10339924912 10750582784 1386263328 14.473 99 81 159 0 1439946144 34433296 19249520
14:49:51 2659 8192000000 10337405728 10752565248 1391701328 14.521 100 77 152 0 1442882080 32161392 19019360
14:50:56 2439 8192000000 10314598352 10717827072 1366416400 14.295 100 88 172 3 1418253776 30563072 21274304
14:52:02 6525 8192000000 10327937120 10741616640 1380985792 14.425 100 62 124 1 1433699600 37459792 15254016
14:53:14 3307 8192000000 10366004464 10782535680 1409516256 14.680 100 84 166 1 1470441664 39318752 21606656
14:54:23 1961 8192000000 10353078544 10767237120 1402953008 14.621 100 78 156 2 1457841888 35596992 19291888
14:55:29 1133 8192000000 10335962128 10742538240 1374067568 14.363 99 81 162 0 1436796320 42131584 20597168
14:56:33 11011 8192000000 10299455408 10725023744 1354203024 14.185 97 32 64 0 1398941584 35654976 9083584
14:57:54 2347 8192000000 10341400672 10747326464 1379666560 14.414 100 87 172 3 1448929584 47098544 22164480
14:58:59 7609 8192000000 10318695728 10745991168 1373580512 14.359 100 40 80 2 1433052112 48635312 10836288
15:00:11 4392 8192000000 10360258560 10769960960 1391354752 14.518 100 74 146 0 1458744912 48117904 19272256
15:01:20 13845 8192000000 10345113072 10777432064 1391766592 14.522 99 39 78 2 1450146512 46981104 11398816
15:02:45 17145 8192000000 10347612496 10782568448 1387271360 14.482 99 34 68 1 1439346960 42880736 9194864
15:04:13 23869 8192000000 10334033696 10768928768 1377316720 14.393 100 28 56 0 1435800016 49799616 8683680
15:05:50 19392 8192000000 10312674320 10760183808 1355016704 14.193 99 17 34 0 1405622752 45491552 5114496
15:07:15 42660 8192000000 10293584368 10742644736 1348921712 14.138 98 26 54 2 1365228464 10087776 6218976
15:09:09 102147 8192000000 10290367744 10734891008 1357864048 14.218 98 13 26 1 1379183168 18693616 2625504
15:11:52 61738 8192000000 10310989344 10759524352 1391041984 14.515 100 13 26 0 1408942240 15357280 2542976
15:14:03 56945 8192000000 10311374832 10740809728 1365201248 14.284 98 28 52 0 1381372240 10106992 6064000
15:16:00 112026 8192000000 10292864256 10734686208 1340771424 14.064 97 39 76 1 1359251008 8761776 9717808
15:18:53 20912 8192000000 10310417584 10747441152 1373789936 14.361 99 44 87 1 1402775552 17921568 11064048
15:20:18 73980 8192000000 10291109168 10740199424 1369413488 14.322 98 15 30 2 1382791728 10321744 3056496
15:22:35 52723 8192000000 10310576208 10765369344 1400025808 14.595 100 17 34 1 1417697680 13687888 3983984
15:24:31 38261 8192000000 10340053120 10799992832 1417108672 14.747 100 43 86 3 1457702576 30465536 10128368
15:26:20 22070 8192000000 10293228384 10755522560 1376930896 14.389 98 15 30 0 1393875568 13255344 3689328
15:27:43 47721 8192000000 10302327248 10750345216 1376316096 14.384 99 12 24 2 1394358704 15403856 2638752
15:29:38 59274 8192000000 10286661184 10751676416 1358405264 14.223 98 13 26 0 1374778480 13396480 2976736
15:31:47 51880 8192000000 10265319504 10709839872 1303228880 13.725 94 14 28 1 1313449568 7193728 3026960
15:33:44 75201 8192000000 10282989040 10730102784 1309272240 13.779 95 10 20 0 1320700896 9192656 2236000
15:36:01 68046 8192000000 10267092304 10712932352 1252340128 13.260 92 11 22 0 1275713296 20310704 3062464
15:38:14 48043 8192000000 10264363776 10718232576 1342748880 14.082 99 11 22 0 1360639664 15494320 2396464
15:40:02 128311 8192000000 10251640688 10678693888 1206583360 12.837 89 9 18 0 1217886112 9645744 1657008
15:43:14 113252 8192000000 10262631504 10719768576 1342952512 14.084 99 36 72 0 1366036976 15028784 8055680
15:46:11 22428 8192000000 10273003296 10720956416 1346605520 14.117 99 28 56 0 1366700224 14023072 6071632
15:48:07 54035 8192000000 10254805456 10719326208 1342182848 14.077 99 4 8 0 1356095920 13039152 873920
15:50:02 40485 8192000000 10269187808 10720206848 1359497712 14.233 99 23 47 0 1373647040 8699040 5450288
15:51:43 66616 8192000000 10287451424 10746630144 1374200832 14.365 100 12 24 0 1385740976 8663760 2876384
15:54:49 94412 8192000000 10278253056 10748899328 1364525376 14.278 99 5 10 0 1387655760 21966672 1163712
15:57:24 42865 8192000000 10284318400 10734395392 1361539680 14.251 99 25 50 0 1378824880 11703024 5582176
15:59:12 53521 8192000000 10281904624 10748411904 1368592224 14.314 99 13 24 2 1383089696 11874448 2623024
16:01:11 38363 8192000000 10307792432 10751930368 1383470192 14.448 100 43 84 1 1405216240 12231456 9514592
16:02:56 33810 8192000000 10291555872 10743848960 1358255376 14.222 98 15 30 0 1370750912 9294112 3201424
16:05:24 67619 8192000000 10292895248 10749476864 1348905520 14.138 97 12 24 0 1365025376 13301536 2818320
16:07:35 46281 8192000000 10315328624 10756259840 1380627008 14.422 99 15 30 0 1394605136 10544096 3434032
16:09:27 101658 8192000000 10274173520 10728148992 1346734256 14.118 97 18 36 0 1362062352 11075760 4252336
16:12:20 15350 8192000000 10301359216 10747875328 1366080080 14.292 98 38 76 1 1383861632 9298608 8482944
16:13:39 79448 8192000000 10306222720 10763759616 1395385904 14.554 100 21 42 1 1412614080 12210592 5017584
16:16:13 71462 8192000000 10299548272 10760871936 1377123392 14.391 99 14 28 0 1388810160 8603424 3083344
16:18:31 63726 8192000000 10283375536 10750517248 1375154576 14.373 99 12 24 0 1389088448 10938544 2995328
16:20:36 29158 8192000000 10286535376 10747015168 1385044480 14.462 100 24 48 0 1402077936 11663824 5369632
16:22:11 130150 8192000000 10283140304 10746871808 1319549776 13.873 95 17 34 1 1336850048 13623072 3677200
16:25:59 39339 8192000000 10277642848 10724368384 1330366128 13.970 97 40 78 2 1352805040 13788064 8650848
16:27:38 104567 8192000000 10262612528 10706079744 1276214384 13.478 93 28 56 0 1298194384 15799920 6180080
16:30:25 24349 8192000000 10274309840 10719158272 1355009984 14.193 100 39 78 0 1376729504 13397696 8321824
16:31:54 94574 8192000000 10261317856 10715484160 1348180384 14.131 100 29 58 1 1373747904 18920160 6647360
16:35:15 83166 8192000000 10236835296 10699120640 1314182480 13.824 96 6 12 0 1326025040 10626544 1216016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-12-2021 16:40:50 to 29-12-2021 16:41:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 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 29-12-2021 16:41:06 to 29-12-2021 16:41:08.

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          247181
MON$OLDEST_ACTIVE               247182
MON$OLDEST_SNAPSHOT             247182
MON$NEXT_TRANSACTION            247182
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                       2048288
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:50: 
:51: mscs-test-161.budzdorov.ru    Wed Dec 29 16:38:15 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Wed Dec 29 16:38:15 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Wed Dec 29 16:38:16 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Wed Dec 29 16:38:16 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Wed Dec 29 16:38:16 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
--- end of diff output ---

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

29.12.21 16:41:09. 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
2021-12-29 16:41:11 No severe PSQL-related problems occured
29.12.21 16:41:12. End of report.