Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.09.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16039628 kB
MemAvailable 30850356 kB
Buffers 0 kB
Cached 15668596 kB
SwapCached 416 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.8
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-09-16 01:37:49
DTS_END                         2021-09-16 01:37:49.5130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45270
TRN_ID                          239201
ATT_ID                          693
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: 9091.70 21268 1091004
customer order: creation 168.81 377 20257
customer order: refuse 13.96 367 1675
order to supplier: creation 45.24 1257 5429
order to supplier: removal 3.25 112307 390
invoice (draft): creation 46.12 3480 5534
invoice (draft): removal 52.19 775 6263
invoice accept: apply 40.72 19977 4886
invoice accept: cancel 47.03 816 5643
customer reserve: creation 3803.84 195 456461
customer reserve: removal 4572.67 63 548720
realization accept: apply 84.21 188 10105
realization accept: cancel 84.00 116 10080
payment from customer: creation 58.98 140 7078
payment from customer: removal 5.88 63 706
payment to supplier: creation 49.31 164 5917
payment to supplier: removal 3.44 95 413
service: total inventory turnovers 4.80 1062 576
service: total monetary turnovers 6.39 45 767
service: aggregate perf. data 0.83 20313 100
service: refresh index statistics 0.03 263568 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-09-2021 01:39:32 to 16-09-2021 01:39:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1628
TEST_TIME 2 1348
TEST_TIME 3 2413
TEST_TIME 4 3251
TEST_TIME 5 3896
TEST_TIME 6 4049
TEST_TIME 7 4437
TEST_TIME 8 5034
TEST_TIME 9 5141
TEST_TIME 10 5150
TEST_TIME 11 5664
TEST_TIME 12 6216
TEST_TIME 13 6312
TEST_TIME 14 6531
TEST_TIME 15 7103
TEST_TIME 16 7478
TEST_TIME 17 7738
TEST_TIME 18 7973
TEST_TIME 19 7817
TEST_TIME 20 7904
TEST_TIME 21 8339
TEST_TIME 22 8423
TEST_TIME 23 8688
TEST_TIME 24 8600
TEST_TIME 25 8951
TEST_TIME 26 9008
TEST_TIME 27 9283
TEST_TIME 28 9332
TEST_TIME 29 9648
TEST_TIME 30 10284
TEST_TIME 31 10738
TEST_TIME 32 10722
TEST_TIME 33 10635
TEST_TIME 34 10368
TEST_TIME 35 10233
TEST_TIME 36 10054
TEST_TIME 37 10217
TEST_TIME 38 10161
TEST_TIME 39 10073
TEST_TIME 40 10020
TEST_TIME 41 9966
TEST_TIME 42 9903
TEST_TIME 43 9850
TEST_TIME 44 9739
TEST_TIME 45 9597
TEST_TIME 46 9541
TEST_TIME 47 9431
TEST_TIME 48 9610
TEST_TIME 49 9510
TEST_TIME 50 9458
TEST_TIME 51 9452
TEST_TIME 52 9341
TEST_TIME 53 9203
TEST_TIME 54 9272
TEST_TIME 55 9255
TEST_TIME 56 9252
TEST_TIME 57 9217
TEST_TIME 58 9162
TEST_TIME 59 9271
TEST_TIME 60 9442
TEST_TIME 61 9437
TEST_TIME 62 9446
TEST_TIME 63 9549
TEST_TIME 64 9527
TEST_TIME 65 9635
TEST_TIME 66 9580
TEST_TIME 67 9689
TEST_TIME 68 9647
TEST_TIME 69 9679
TEST_TIME 70 9585
TEST_TIME 71 9487
TEST_TIME 72 9475
TEST_TIME 73 9461
TEST_TIME 74 9372
TEST_TIME 75 9322
TEST_TIME 76 9261
TEST_TIME 77 9270
TEST_TIME 78 9275
TEST_TIME 79 9275
TEST_TIME 80 9290
TEST_TIME 81 9274
TEST_TIME 82 9294
TEST_TIME 83 9315
TEST_TIME 84 9407
TEST_TIME 85 9378
TEST_TIME 86 9380
TEST_TIME 87 9373
TEST_TIME 88 9409
TEST_TIME 89 9468
TEST_TIME 90 9458
TEST_TIME 91 9375
TEST_TIME 92 9360
TEST_TIME 93 9303
TEST_TIME 94 9306
TEST_TIME 95 9283
TEST_TIME 96 9234
TEST_TIME 97 9189
TEST_TIME 98 9143
TEST_TIME 99 9221
TEST_TIME 100 9224
TEST_TIME 101 9277
TEST_TIME 102 9291
TEST_TIME 103 9325
TEST_TIME 104 9327
TEST_TIME 105 9335
TEST_TIME 106 9403
TEST_TIME 107 9368
TEST_TIME 108 9364
TEST_TIME 109 9379
TEST_TIME 110 9384
TEST_TIME 111 9332
TEST_TIME 112 9292
TEST_TIME 113 9271
TEST_TIME 114 9262
TEST_TIME 115 9239
TEST_TIME 116 9201
TEST_TIME 117 9172
TEST_TIME 118 9140
TEST_TIME 119 9096
TEST_TIME 120 9092

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-09-2021 01:39:36 to 16-09-2021 01:39:39.

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 1513652 1513652 0 0.00 0 31379 7 0 0
doc_list_biud 1513651 1513651 0 0.00 0 38847 29 0 0
sp_add_invoice_to_stock 4983 4886 97 1.94 29 188329 19978 0 97
sp_cancel_adding_invoice 5643 5643 0 0.00 4 56720 816 0 0
sp_cancel_client_order 1675 1675 0 0.00 4 13216 367 0 0
sp_cancel_customer_reserve 548720 548720 0 0.00 1 38851 64 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 3 1424 64 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 3856 95 0 0
sp_cancel_supplier_invoice 6266 6263 3 0.04 1 29263 776 0 3
sp_cancel_supplier_order 390 390 0 0.00 11 1371158 112308 0 0
sp_cancel_write_off 10080 10080 0 0.00 1 25610 117 0 0
sp_client_order 20257 20257 0 0.00 3 40855 378 0 0
sp_customer_reserve 456461 456461 0 0.00 0 46386 195 0 0
sp_fill_shopping_cart 31213 31213 0 0.00 1 15277 40 0 0
sp_fill_shopping_cart_clo_res 456460 456460 0 0.00 0 20287 15 0 0
sp_get_clo_for_invoice 4884 4884 0 0.00 0 27622 339 0 0
sp_kill_cost_storno 16842 16842 0 0.00 0 31368 63 0 0
sp_kill_qstorno_ret_qs2qd 1114173 1114173 0 0.00 0 38843 34 0 0
sp_kill_qty_storno 587760 587760 0 0.00 0 38847 74 0 0
sp_lock_selected_doc 20715 20715 0 0.00 0 7948 7 0 0
sp_make_cost_storno 27984 27984 0 0.00 0 18380 65 0 0
sp_multiply_rows_for_pdistr 27984 27984 0 0.00 0 22645 33 0 0
sp_multiply_rows_for_qdistr 20257 20257 0 0.00 0 40787 171 0 0
sp_pay_from_customer 7078 7078 0 0.00 4 18388 141 0 0
sp_pay_to_supplier 5917 5917 0 0.00 5 22685 165 0 0
sp_payment_common 12995 12995 0 0.00 1 13056 39 0 0
sp_qd_handle_on_cancel_clo 1675 1675 0 0.00 0 13206 346 0 0
sp_qd_handle_on_invoice_upd_sts 10527 10527 0 0.00 0 34386 351 0 0
sp_qd_handle_on_reserve_upd_sts 10105 10105 0 0.00 0 10170 60 0 0
sp_reserve_write_off 10105 10105 0 0.00 6 13198 188 0 0
sp_supplier_invoice 5534 5534 0 0.00 5 82244 3480 0 0
sp_supplier_order 5429 5429 0 0.00 5 37633 1257 0 0
srv_aggregate_perf_data 100 100 0 0.00 2378 174620 20313 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 1422 118615 38870 0 0
srv_find_qd_qs_mism 1043653 1043653 0 0.00 0 26095 6 0 0
srv_make_invnt_saldo 576 576 0 0.00 32 36992 1063 0 0
srv_make_money_saldo 767 767 0 0.00 0 12277 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 173357 395383 263569 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4886 4886 0 0.00 0 18199 200 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 8978 120 0 0
t$perf-norm:sp_cancel_client_order 1675 1675 0 0.00 0 9100 18 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 969 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 4737 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 6452 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 12049 54 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 25291 1728 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 1247 6 0 0
t$perf-norm:sp_client_order 20257 20257 0 0.00 0 15663 18 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 1292 6 0 0
t$perf-norm:sp_pay_from_customer 7078 7078 0 0.00 0 12957 16 0 0
t$perf-norm:sp_pay_to_supplier 5917 5917 0 0.00 0 8635 12 0 0
t$perf-norm:sp_reserve_write_off 10105 10105 0 0.00 0 8567 15 0 0
t$perf-norm:sp_supplier_invoice 5534 5534 0 0.00 0 9083 21 0 0
t$perf-norm:sp_supplier_order 5429 5429 0 0.00 0 4586 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 422 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 63 2 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 2750 25 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 4962 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9960 9766 194 1.94 0 6361 17 0 194
v_all_customers 36962 36962 0 0.00 0 93 1 0 0
v_all_suppliers 30476 30476 0 0.00 0 29 1 0 0
v_all_wares 40512 40512 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1151 9 0 0
v_cancel_client_order 3350 3350 0 0.00 0 1031 3 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 1305 4 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 2886 7 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 2822 11 0 0
v_cancel_supplier_invoice 804 798 6 0.74 0 1992 17 0 6
v_cancel_supplier_order 780 780 0 0.00 0 1612 9 0 0
v_cancel_write_off 2010 2010 0 0.00 0 2309 16 0 0
v_max_id_clo_ord 5429 5429 0 0.00 0 3900 2 0 0
v_max_id_clo_res 999 999 0 0.00 0 1084 7 0 0
v_max_id_ord_sup 5528 5528 0 0.00 0 4981 4 0 0
v_max_non_paid_invoice 1636 1636 0 0.00 0 5901 11 0 0
v_max_non_paid_realizn 3806 3806 0 0.00 0 4863 7 0 0
v_min_id_clo_ord 5429 5429 0 0.00 0 3677 8 0 0
v_min_id_clo_res 999 999 0 0.00 0 6736 16 0 0
v_min_id_ord_sup 5528 5528 0 0.00 0 15078 10 0 0
v_min_non_paid_invoice 5917 5917 0 0.00 0 7956 27 0 0
v_min_non_paid_realizn 7078 7078 0 0.00 0 6302 22 0 0
v_our_firm 10094 10094 0 0.00 0 25 1 0 0
v_reserve_write_off 20210 20210 0 0.00 0 4568 6 0 0
x_lock_depdocs_on_canc_invoice 11507 11507 0 0.00 0 24107 127 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 34558 495 0 0
x_make_qstorno_1000_1200 5429 5429 0 0.00 1 37625 1171 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 2 82231 3343 0 0
x_make_qstorno_2100_3300 456461 456461 0 0.00 0 45011 178 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-09-2021 01:39:40 to 16-09-2021 01:39:45.

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:07:53 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:54 26781 8192000000 10366576608 10687131648 1413690416 14.717 100 88 176 0 1448894288 6099392 29104480
23:10:24 23860 8192000000 10418202704 10749698048 1476345184 15.269 100 87 174 0 1513005632 8210528 28449920
23:11:52 12855 8192000000 10469188800 10808004608 1522100336 15.668 100 82 164 0 1557662080 9130496 26431248
23:13:06 14418 8192000000 10549112816 10905206784 1601799936 16.355 100 79 158 1 1636520896 10420112 24300848
23:14:23 13745 8192000000 10626212400 10991357952 1684024880 17.051 100 84 166 2 1720171184 11889920 24256384
23:15:41 7878 8192000000 10682347648 11061673984 1747230528 17.579 100 76 153 0 1781229936 12215920 21783488
23:16:51 3164 8192000000 10739595536 11124846592 1804993872 18.055 100 75 150 1 1837746800 11891728 20861200
23:17:57 2605 8192000000 10783955104 11168198656 1844407616 18.377 100 92 186 0 1884369904 14234320 25727968
23:19:02 2155 8192000000 10810123648 11199389696 1866627024 18.557 100 92 184 3 1906933968 14359168 25947776
23:20:06 5149 8192000000 10784914512 11200966656 1866754928 18.558 100 33 66 1 1884242528 8013120 9474480
23:21:13 6557 8192000000 10784394160 11209404416 1875894000 18.632 100 27 54 2 1890579136 7213488 7471648
23:22:24 14378 8192000000 10812569408 11237625856 1898446736 18.814 100 17 34 1 1909277744 6449792 4381216
23:23:42 46322 8192000000 10819830624 11253473280 1901962112 18.842 100 26 52 1 1918930736 10094464 6874160
23:25:34 6404 8192000000 10833102384 11266101248 1911182144 18.916 100 29 58 0 1929937824 10451680 8304000
23:26:44 8043 8192000000 10816443712 11256082432 1905831648 18.873 100 13 26 0 1914724928 5262352 3630928
23:27:56 66416 8192000000 10786573984 11211964416 1778828720 17.840 94 37 74 0 1794321280 5193856 10298704
23:30:11 5216 8192000000 10720244208 11129434112 1760334672 17.687 97 29 58 2 1773484880 4856976 8293232
23:31:17 9888 8192000000 10688958528 11100393472 1692737744 17.124 94 18 36 0 1702714256 4784256 5192256
23:32:28 12116 8192000000 10718500448 11136872448 1805774144 18.061 100 22 44 1 1817635248 5260064 6601040
23:33:40 34086 8192000000 10744673040 11164925952 1810347840 18.099 99 26 50 1 1823345600 6211520 6786240
23:35:16 10199 8192000000 10788850752 11216379904 1867857216 18.567 100 30 59 2 1886837136 9832704 9147216
23:36:28 13303 8192000000 10800849296 11229745152 1879651952 18.662 100 26 51 0 1893775184 6260368 7862864
23:37:42 11834 8192000000 10849703744 11264536576 1909256400 18.901 100 11 22 0 1916876864 4508864 3111600
23:39:08 77392 8192000000 10869095120 11300536320 1937803264 19.129 100 48 96 0 1961774560 10237680 13733616
23:41:31 17603 8192000000 10898172560 11325341696 1953261872 19.252 100 30 60 0 1975702784 12815248 9625664
23:42:59 3853 8192000000 10923190384 11348942848 1974714256 19.423 100 57 110 0 2007183008 17206496 15262256
23:44:03 4945 8192000000 10942877216 11370897408 1991167600 19.553 100 59 118 0 2029717440 20908976 17640864
23:45:10 1421 8192000000 10950739312 11381100544 1998373168 19.610 100 64 129 1 2040542368 22728672 19440528
23:46:16 8758 8192000000 10964423216 11386384384 2004857312 19.661 100 57 113 1 2043142416 20831168 17453936
23:47:28 8107 8192000000 10976907824 11401580544 2015051936 19.741 100 69 137 1 2062160208 26741888 20366384
23:48:38 2202 8192000000 10972263984 11399757824 2011072800 19.710 100 75 147 2 2060941952 26869744 22999408
23:49:47 7385 8192000000 10975554736 11405107200 2011047696 19.710 100 72 142 1 2058368992 26634672 20686624
23:51:05 4142 8192000000 10962488304 11381194752 1990736432 19.550 100 80 159 0 2044618624 30213872 23668320
23:52:12 1929 8192000000 10939013744 11351748608 1949153488 19.220 99 81 161 0 2007306176 34317152 23835536
23:53:20 1805 8192000000 10946354576 11369091072 1974737344 19.423 100 66 125 1 2027130432 32956976 19436112
23:54:28 9990 8192000000 10919373216 11357077504 1967587712 19.366 100 44 88 0 2005013200 23337232 14088256
23:55:40 9609 8192000000 10900027904 11339141120 1942698448 19.168 100 42 82 1 1988914320 33182032 13033840
23:56:55 2844 8192000000 10955127088 11369672704 1970434944 19.389 100 85 170 1 2032967824 35457248 27075632
23:58:03 5928 8192000000 10966152032 11389022208 1981808160 19.479 100 64 127 2 2043999504 41481408 20709936
23:59:13 5937 8192000000 10956149216 11389583360 1978818416 19.455 100 59 116 1 2038173168 41519680 17835072
00:00:24 6539 8192000000 10976560144 11407110144 1987838288 19.527 100 59 118 1 2055727824 49117008 18772528
00:01:37 18195 8192000000 10932581536 11367714816 1943898592 19.178 100 37 72 2 2009650848 53707280 12044976
00:03:01 10629 8192000000 10940111744 11383390208 1955222704 19.268 100 36 71 0 2024431264 56741152 12467408
00:04:16 11254 8192000000 10939736640 11382308864 1938717664 19.137 99 33 66 1 2008484976 58215168 11552144
00:05:34 16751 8192000000 10928256800 11374489600 1924334416 19.022 99 26 52 0 1991502016 58225712 8941888
00:06:53 21750 8192000000 10914264496 11369926656 1934763696 19.105 100 20 40 1 1991536096 50066720 6705680
00:08:16 15582 8192000000 10886848128 11328212992 1907175648 18.884 98 23 46 1 1938806608 24114672 7516288
00:09:34 84163 8192000000 10895108336 11359399936 1936774576 19.121 99 13 26 0 1953094432 13332096 2987760
00:12:01 87563 8192000000 10905787904 11359690752 1945451328 19.190 99 26 52 0 1968391792 15317552 7622912
00:14:29 30872 8192000000 10905100880 11353747456 1953257760 19.252 100 54 106 2 1981454048 12615120 15581168
00:16:08 83097 8192000000 10884280608 11350020096 1938842464 19.138 99 12 24 1 1953885488 11206144 3836880
00:18:32 33356 8192000000 10866715328 11335364608 1933678528 19.096 99 29 57 0 1950695408 9183728 7833152
00:20:07 33885 8192000000 10875691056 11346837504 1956589856 19.279 100 7 14 0 1968045024 9649616 1805552
00:21:42 36184 8192000000 10900893856 11365220352 1974473040 19.421 100 14 28 0 1986895184 8466400 3955744
00:23:19 69892 8192000000 10894975856 11366801408 1972511984 19.405 100 18 36 1 1986980400 9380960 5087456
00:25:33 36612 8192000000 10894301792 11366133760 1977458400 19.445 100 19 37 2 1988851984 5990352 5403232
00:27:13 3625 8192000000 10905744704 11372974080 1969803472 19.384 99 26 50 2 1984705056 7784000 7117584
00:28:17 52076 8192000000 10899926720 11380862976 1973295248 19.412 99 19 38 3 1985932912 7117744 5519920
00:30:21 84503 8192000000 10868968800 11354132480 1936677440 19.120 97 21 43 0 1951189984 8422656 6089888
00:32:48 27540 8192000000 10889195616 11369566208 1966538336 19.358 99 11 22 1 1977218272 7852048 2827888
00:34:23 37395 8192000000 10910220208 11385167872 1956862016 19.281 98 31 62 2 1979385344 13436800 9086528
00:36:02 39956 8192000000 10902409072 11373330432 1926346368 19.038 97 14 28 1 1950848656 19989776 4512512
00:37:45 39241 8192000000 10876610128 11349282816 1919374176 18.982 98 15 30 0 1937684864 14054240 4256448
00:39:33 50065 8192000000 10871609952 11356708864 1899516304 18.822 97 12 24 1 1923501504 20537632 3447568
00:41:24 56087 8192000000 10873141376 11347595264 1908162656 18.892 97 21 41 3 1924184800 10629312 5392832
00:43:49 11077 8192000000 10910893568 11350233088 1927165904 19.044 98 54 107 1 1957130624 14055392 15909328
00:45:00 41454 8192000000 10879399280 11330580480 1913158720 18.932 98 20 40 1 1932277504 13771136 5347648
00:46:46 113085 8192000000 10848169520 11314638848 1903420704 18.854 98 28 56 2 1920199744 7385904 9393136
00:49:42 35184 8192000000 10849044912 11322191872 1916156416 18.956 99 6 12 0 1925591840 7872352 1563072
00:51:24 32257 8192000000 10848677472 11328638976 1931565360 19.079 100 6 12 1 1945672672 12493200 1614112
00:52:57 61492 8192000000 10862762672 11331919872 1885763984 18.712 97 20 40 0 1907348624 16011264 5573376
00:54:59 87039 8192000000 10866033280 11340500992 1860106384 18.504 96 8 16 0 1873500304 11317952 2075968
00:57:27 36012 8192000000 10843096384 11317473280 1881300416 18.676 97 19 36 1 1902474992 15548496 5626080
00:59:09 41699 8192000000 10876511360 11343286272 1942201392 19.164 100 17 34 0 1960241376 12911168 5128816
01:00:51 54274 8192000000 10872580912 11350331392 1918828864 18.977 98 26 52 1 1936657424 10389616 7438944
01:02:47 81191 8192000000 10882680448 11347685376 1893273232 18.772 97 21 42 2 1913953216 14956320 5723664
01:05:19 81214 8192000000 10865153728 11349602304 1896781456 18.800 97 27 54 0 1918710128 14614400 7314272
01:07:52 102566 8192000000 10872791968 11346407424 1926261392 19.037 98 22 44 0 1946857872 14202192 6394288
01:10:38 22394 8192000000 10920921232 11372326912 1979817744 19.463 100 47 94 1 2003089376 10447664 12823968
01:12:01 36316 8192000000 10885510384 11369385984 1972736768 19.407 100 8 16 0 1986050336 11117392 2196176
01:13:37 59444 8192000000 10902961296 11378266112 1973813168 19.416 100 13 26 0 1989366064 11512400 4040496
01:15:42 70669 8192000000 10916251888 11409080320 1970341840 19.388 99 13 26 0 1997017632 22159312 4516480
01:17:53 60401 8192000000 10904214976 11382374400 1946897536 19.202 98 16 31 1 1965987312 14108896 4980880
01:20:14 51118 8192000000 10881848528 11361558528 1918512688 18.975 98 11 22 0 1932983216 10806256 3664272
01:22:06 68029 8192000000 10886635872 11356913664 1920083536 18.988 98 18 36 1 1943046368 15453840 7508992
01:24:39 15016 8192000000 10900140400 11357208576 1946348512 19.197 99 43 86 2 1966297680 7924096 12025072
01:25:59 71444 8192000000 10902325056 11363438592 1942667392 19.168 99 25 50 2 1963376336 13880464 6828480
01:28:12 118615 8192000000 10855212416 11332612096 1932011968 19.083 100 25 50 2 1949280400 9526688 7741744
01:31:25 43057 8192000000 10826633424 11306528768 1878234976 18.651 98 10 20 0 1893059840 11788672 3036192
01:33:08 45726 8192000000 10858779184 11331125248 1923933088 19.018 99 14 28 0 1935712704 7741920 4037696
01:35:09 86274 8192000000 10823981856 11295756288 1805766560 18.061 94 7 14 1 1818260992 9775888 2718544
01:37:41 21252 8192000000 9353415136 9718882304 349498048 4.091 20 3 6 0 355234544 5339488 397008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-09-2021 01:39:46 to 16-09-2021 01:40:02.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-09-2021 01:40:03 to 16-09-2021 01:40:05.

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          239478
MON$OLDEST_ACTIVE               239479
MON$OLDEST_SNAPSHOT             239479
MON$NEXT_TRANSACTION            239479
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                       2042592
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33496 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33496 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33496 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33496 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Sep 16 01:38:04 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Sep 16 01:38:07 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Sep 16 01:38:08 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Sep 16 01:38:16 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Sep 16 01:38:16 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
--- end of diff output ---

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

16.09.21 01:40:06. 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-09-16 01:40:08 No severe PSQL-related problems occured
16.09.21 01:40:09. End of report.